2017-12-08T17:37:42.011Z,1512754662.011 [Supervisor](DEBUG): Initializing supervisor.
2017-12-08T17:37:42.025Z,1512754662.025 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2017-12-08T17:37:42.025Z,1512754662.025 [SyncHandler](INFO): Protected caller Thread ID is 803
2017-12-08T17:37:42.026Z,1512754662.026 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-12-08T17:37:42.037Z,1512754662.037 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2017-12-08T17:37:42.037Z,1512754662.037 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 804
2017-12-08T17:37:42.040Z,1512754662.040 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-12-08T17:37:42.062Z,1512754662.062 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-12-08T17:37:42.073Z,1512754662.073 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2017-12-08T17:37:42.073Z,1512754662.073 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 805
2017-12-08T17:37:42.074Z,1512754662.074 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-12-08T17:37:42.085Z,1512754662.085 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2017-12-08T17:37:42.085Z,1512754662.085 [logger ThreadHandler](INFO): Protected caller Thread ID is 806
2017-12-08T17:37:42.087Z,1512754662.087 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-12-08T17:37:42.087Z,1512754662.087 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-12-08T17:37:42.092Z,1512754662.092 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-12-08T17:37:42.283Z,1512754662.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-12-08T17:37:42.285Z,1512754662.285 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-12-08T17:37:42.467Z,1512754662.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-12-08T17:37:42.469Z,1512754662.469 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-12-08T17:37:42.664Z,1512754662.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-12-08T17:37:42.664Z,1512754662.664 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-12-08T17:37:42.955Z,1512754662.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-12-08T17:37:42.957Z,1512754662.957 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-12-08T17:37:43.268Z,1512754663.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-12-08T17:37:43.270Z,1512754663.270 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-12-08T17:37:43.705Z,1512754663.705 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-12-08T17:37:43.705Z,1512754663.705 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-12-08T17:37:44.031Z,1512754664.031 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-12-08T17:37:44.032Z,1512754664.032 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-12-08T17:37:44.450Z,1512754664.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-12-08T17:37:44.452Z,1512754664.452 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-12-08T17:37:44.535Z,1512754664.535 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-12-08T17:37:45.318Z,1512754665.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-12-08T17:37:45.319Z,1512754665.319 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-12-08T17:37:45.458Z,1512754665.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-12-08T17:37:45.459Z,1512754665.459 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-12-08T17:37:45.653Z,1512754665.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-12-08T17:37:45.655Z,1512754665.655 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-12-08T17:37:45.848Z,1512754665.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-12-08T17:37:45.849Z,1512754665.849 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-12-08T17:37:45.945Z,1512754665.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-12-08T17:37:45.945Z,1512754665.945 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-12-08T17:37:46.083Z,1512754666.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-12-08T17:37:46.085Z,1512754666.085 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2017-12-08T17:37:46.087Z,1512754666.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2017-12-08T17:37:46.313Z,1512754666.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-12-08T17:37:46.313Z,1512754666.313 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2017-12-08T17:37:46.391Z,1512754666.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2017-12-08T17:37:46.530Z,1512754666.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2017-12-08T17:37:46.623Z,1512754666.623 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2017-12-08T17:37:46.707Z,1512754666.707 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2017-12-08T17:37:46.839Z,1512754666.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2017-12-08T17:37:47.001Z,1512754667.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2017-12-08T17:37:47.097Z,1512754667.097 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2017-12-08T17:37:47.188Z,1512754667.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2017-12-08T17:37:47.284Z,1512754667.284 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2017-12-08T17:37:47.366Z,1512754667.366 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2017-12-08T17:37:47.370Z,1512754667.370 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-12-08T17:37:47.490Z,1512754667.490 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-12-08T17:37:47.491Z,1512754667.491 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-12-08T17:37:47.514Z,1512754667.514 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-12-08T17:37:47.515Z,1512754667.515 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-12-08T17:37:47.568Z,1512754667.568 [DepthRateCalculator] Loaded
2017-12-08T17:37:47.568Z,1512754667.568 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-12-08T17:37:47.574Z,1512754667.574 [PitchRateCalculator] Loaded
2017-12-08T17:37:47.574Z,1512754667.574 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-12-08T17:37:47.590Z,1512754667.590 [SpeedCalculator] Loaded
2017-12-08T17:37:47.590Z,1512754667.590 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-12-08T17:37:47.611Z,1512754667.611 [TempGradientCalculator] Loaded
2017-12-08T17:37:47.611Z,1512754667.611 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-12-08T17:37:47.626Z,1512754667.626 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-12-08T17:37:47.627Z,1512754667.627 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-12-08T17:37:47.632Z,1512754667.632 [YawRateCalculator] Loaded
2017-12-08T17:37:47.632Z,1512754667.632 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-12-08T17:37:47.633Z,1512754667.633 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-12-08T17:37:47.634Z,1512754667.634 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-12-08T17:37:47.669Z,1512754667.669 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-12-08T17:37:47.670Z,1512754667.670 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-12-08T17:37:48.114Z,1512754668.114 [AcousticModem_Benthos_ATM900] Loaded
2017-12-08T17:37:48.114Z,1512754668.114 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2017-12-08T17:37:48.253Z,1512754668.253 [DataOverHttps] Loaded
2017-12-08T17:37:48.253Z,1512754668.253 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-12-08T17:37:48.266Z,1512754668.266 [Depth_Keller] Loaded
2017-12-08T17:37:48.266Z,1512754668.266 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-12-08T17:37:48.271Z,1512754668.271 [DropWeight] Loaded
2017-12-08T17:37:48.271Z,1512754668.271 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-12-08T17:37:48.365Z,1512754668.365 [NAL9602] Loaded
2017-12-08T17:37:48.365Z,1512754668.365 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-12-08T17:37:48.380Z,1512754668.380 [Onboard] Loaded
2017-12-08T17:37:48.380Z,1512754668.380 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-12-08T17:37:48.390Z,1512754668.390 [Radio_Surface] Loaded
2017-12-08T17:37:48.390Z,1512754668.390 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-12-08T17:37:48.391Z,1512754668.391 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A24E0
2017-12-08T17:37:48.392Z,1512754668.392 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 885
2017-12-08T17:37:48.543Z,1512754668.543 [PNI_TCM] Loaded
2017-12-08T17:37:48.543Z,1512754668.543 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-12-08T17:37:48.601Z,1512754668.601 [Rowe_600LCM] Loaded
2017-12-08T17:37:48.602Z,1512754668.602 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-12-08T17:37:48.603Z,1512754668.603 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D24E0
2017-12-08T17:37:48.603Z,1512754668.603 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 886
2017-12-08T17:37:50.474Z,1512754670.474 [BPC1] Loaded
2017-12-08T17:37:50.475Z,1512754670.475 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-12-08T17:37:50.475Z,1512754670.475 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-12-08T17:37:50.574Z,1512754670.574 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-12-08T17:37:50.734Z,1512754670.734 [SBIT](DEBUG): Construct Startup Built In Test.
2017-12-08T17:37:50.760Z,1512754670.760 [SBIT] Loaded
2017-12-08T17:37:50.761Z,1512754670.761 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-12-08T17:37:50.761Z,1512754670.761 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-12-08T17:37:50.772Z,1512754670.772 [IBIT] Loaded
2017-12-08T17:37:50.773Z,1512754670.773 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-12-08T17:37:50.776Z,1512754670.776 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-12-08T17:37:50.923Z,1512754670.923 [CBIT] Loaded
2017-12-08T17:37:50.923Z,1512754670.923 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-12-08T17:37:50.924Z,1512754670.924 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-12-08T17:37:50.924Z,1512754670.924 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-12-08T17:37:51.104Z,1512754671.104 [Aanderaa_O2] Loaded
2017-12-08T17:37:51.104Z,1512754671.104 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-12-08T17:37:51.179Z,1512754671.179 [CTD_NeilBrown] Loaded
2017-12-08T17:37:51.179Z,1512754671.179 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-12-08T17:37:51.180Z,1512754671.180 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408994E0
2017-12-08T17:37:51.181Z,1512754671.181 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 887
2017-12-08T17:37:51.212Z,1512754671.212 [CTD_Seabird] Loaded
2017-12-08T17:37:51.212Z,1512754671.212 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-12-08T17:37:51.213Z,1512754671.213 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408C94E0
2017-12-08T17:37:51.214Z,1512754671.214 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 888
2017-12-08T17:37:51.230Z,1512754671.230 [ESPComponent] Loaded
2017-12-08T17:37:51.231Z,1512754671.231 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2017-12-08T17:37:51.245Z,1512754671.245 [PAR_Licor] Loaded
2017-12-08T17:37:51.245Z,1512754671.245 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-12-08T17:37:51.292Z,1512754671.292 [WetLabsBB2FL] Loaded
2017-12-08T17:37:51.293Z,1512754671.293 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-12-08T17:37:51.294Z,1512754671.294 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408F94E0
2017-12-08T17:37:51.294Z,1512754671.294 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 889
2017-12-08T17:37:51.336Z,1512754671.336 [WetLabsSeaOWL_UV_A] Loaded
2017-12-08T17:37:51.336Z,1512754671.336 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2017-12-08T17:37:51.337Z,1512754671.337 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409294E0
2017-12-08T17:37:51.338Z,1512754671.338 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 890
2017-12-08T17:37:51.338Z,1512754671.338 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-12-08T17:37:51.339Z,1512754671.339 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-12-08T17:37:51.603Z,1512754671.603 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-12-08T17:37:51.604Z,1512754671.604 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-12-08T17:37:51.687Z,1512754671.687 [VerticalControl](DEBUG): Construct VerticalControl.
2017-12-08T17:37:51.782Z,1512754671.782 [VerticalControl] Loaded
2017-12-08T17:37:51.783Z,1512754671.783 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-12-08T17:37:51.783Z,1512754671.783 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-12-08T17:37:51.844Z,1512754671.844 [HorizontalControl] Loaded
2017-12-08T17:37:51.844Z,1512754671.844 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-12-08T17:37:51.845Z,1512754671.845 [SpeedControl](DEBUG): Construct SpeedControl.
2017-12-08T17:37:51.850Z,1512754671.850 [SpeedControl] Loaded
2017-12-08T17:37:51.850Z,1512754671.850 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-12-08T17:37:51.851Z,1512754671.851 [LoopControl](DEBUG): Construct LoopControl.
2017-12-08T17:37:51.851Z,1512754671.851 [LoopControl] Loaded
2017-12-08T17:37:51.852Z,1512754671.852 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-12-08T17:37:51.852Z,1512754671.852 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-12-08T17:37:51.853Z,1512754671.853 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-12-08T17:37:51.974Z,1512754671.974 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-12-08T17:37:51.974Z,1512754671.974 [StratificationFrontDetector](DEBUG): (re)initializing
2017-12-08T17:37:51.974Z,1512754671.974 [StratificationFrontDetector] Loaded
2017-12-08T17:37:51.975Z,1512754671.975 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-12-08T17:37:51.975Z,1512754671.975 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-12-08T17:37:51.976Z,1512754671.976 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-12-08T17:37:52.091Z,1512754672.091 [BuoyancyServo] Loaded
2017-12-08T17:37:52.091Z,1512754672.091 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-12-08T17:37:52.102Z,1512754672.102 [ElevatorServo] Loaded
2017-12-08T17:37:52.103Z,1512754672.103 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-12-08T17:37:52.113Z,1512754672.113 [MassServo] Loaded
2017-12-08T17:37:52.113Z,1512754672.113 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-12-08T17:37:52.124Z,1512754672.124 [RudderServo] Loaded
2017-12-08T17:37:52.124Z,1512754672.124 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-12-08T17:37:52.135Z,1512754672.135 [ThrusterServo] Loaded
2017-12-08T17:37:52.135Z,1512754672.135 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-12-08T17:37:52.136Z,1512754672.136 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-12-08T17:37:52.136Z,1512754672.136 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-12-08T17:37:52.247Z,1512754672.247 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-12-08T17:37:52.248Z,1512754672.248 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-12-08T17:37:52.302Z,1512754672.302 [DeadReckonUsingSpeedCalculator] Loaded
2017-12-08T17:37:52.302Z,1512754672.302 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-12-08T17:37:52.318Z,1512754672.318 [NavChart] Loaded
2017-12-08T17:37:52.318Z,1512754672.318 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-12-08T17:37:52.322Z,1512754672.322 [UniversalFixResidualReporter] Loaded
2017-12-08T17:37:52.322Z,1512754672.322 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-12-08T17:37:52.322Z,1512754672.322 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-12-08T17:37:52.326Z,1512754672.326 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-12-08T17:37:52.327Z,1512754672.327 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-12-08T17:37:52.333Z,1512754672.333 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-12-08T17:37:52.334Z,1512754672.334 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A7B4E0
2017-12-08T17:37:52.335Z,1512754672.335 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 891
2017-12-08T17:37:52.340Z,1512754672.339 [Supervisor](INFO): Main Thread ID is 799
2017-12-08T17:37:52.340Z,1512754672.340 [Supervisor](DEBUG): Running supervisor.
2017-12-08T17:37:52.340Z,1512754672.340 [CommandLine ThreadHandler](INFO): Handler Thread ID is 892
2017-12-08T17:37:52.343Z,1512754672.343 [controlThread ThreadHandler](INFO): Handler Thread ID is 893
2017-12-08T17:37:52.343Z,1512754672.343 [controlThread](DEBUG): Initializing ControlThread
2017-12-08T17:37:52.344Z,1512754672.344 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-12-08T17:37:52.344Z,1512754672.344 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-12-08T17:37:52.345Z,1512754672.345 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-12-08T17:37:52.345Z,1512754672.345 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-12-08T17:37:52.346Z,1512754672.346 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-12-08T17:37:52.346Z,1512754672.346 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-12-08T17:37:52.351Z,1512754672.351 [SBIT](INFO): Initialize SBIT Component.
2017-12-08T17:37:52.352Z,1512754672.352 [SBIT](IMPORTANT): git: 2017-11-20A-2-g40c84ee
2017-12-08T17:37:52.352Z,1512754672.352 [SBIT](INFO): git hash: 40c84eed00c272bed270104db0bfbfef219794f5
2017-12-08T17:37:52.352Z,1512754672.352 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2017-12-08T17:37:52.353Z,1512754672.353 [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-08T17:37:52.354Z,1512754672.354 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2017-12-08T17:37:52.355Z,1512754672.355 [IBIT](INFO): Initialize IBIT Component.
2017-12-08T17:37:52.356Z,1512754672.356 [CBIT](DEBUG): Initialize CBIT Component.
2017-12-08T17:37:52.356Z,1512754672.356 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2017-12-08T17:37:52.356Z,1512754672.356 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-12-08T17:37:52.357Z,1512754672.357 [logger ThreadHandler](INFO): Handler Thread ID is 894
2017-12-08T17:37:52.377Z,1512754672.377 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 895
2017-12-08T17:37:52.383Z,1512754672.383 [Radio_Surface](INFO): Powering up
2017-12-08T17:37:52.389Z,1512754672.389 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 896
2017-12-08T17:37:52.390Z,1512754672.390 [Rowe_600LCM](INFO): Initializing
2017-12-08T17:37:52.390Z,1512754672.390 [Rowe_600LCM](INFO): Checking LCM
2017-12-08T17:37:52.445Z,1512754672.445 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-12-08T17:37:52.447Z,1512754672.447 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-12-08T17:37:52.447Z,1512754672.447 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-12-08T17:37:52.448Z,1512754672.448 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-12-08T17:37:52.453Z,1512754672.453 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-12-08T17:37:52.454Z,1512754672.454 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-12-08T17:37:52.454Z,1512754672.454 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-12-08T17:37:52.455Z,1512754672.455 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-12-08T17:37:52.455Z,1512754672.455 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-12-08T17:37:52.456Z,1512754672.456 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-12-08T17:37:52.456Z,1512754672.456 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-12-08T17:37:52.456Z,1512754672.456 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-12-08T17:37:52.521Z,1512754672.521 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-12-08T17:37:52.532Z,1512754672.532 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 897
2017-12-08T17:37:52.534Z,1512754672.534 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 898
2017-12-08T17:37:52.534Z,1512754672.534 [CTD_Seabird](INFO): Initializing
2017-12-08T17:37:52.557Z,1512754672.557 [CTD_NeilBrown](INFO): Powering down
2017-12-08T17:37:52.709Z,1512754672.709 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 900
2017-12-08T17:37:52.711Z,1512754672.711 [WetLabsBB2FL](INFO): Powering down
2017-12-08T17:37:52.737Z,1512754672.737 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 901
2017-12-08T17:37:52.749Z,1512754672.749 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 902
2017-12-08T17:37:52.759Z,1512754672.759 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-12-08T17:37:52.759Z,1512754672.759 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-12-08T17:37:52.759Z,1512754672.759 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-12-08T17:37:52.759Z,1512754672.759 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-12-08T17:37:52.760Z,1512754672.760 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-12-08T17:37:52.760Z,1512754672.760 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-12-08T17:37:52.760Z,1512754672.760 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-12-08T17:37:52.760Z,1512754672.760 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-12-08T17:37:52.760Z,1512754672.760 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-12-08T17:37:52.760Z,1512754672.760 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-12-08T17:37:52.761Z,1512754672.761 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-12-08T17:37:52.761Z,1512754672.761 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-12-08T17:37:52.761Z,1512754672.761 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-12-08T17:37:52.761Z,1512754672.761 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-12-08T17:37:52.761Z,1512754672.761 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-12-08T17:37:52.762Z,1512754672.762 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-12-08T17:37:52.794Z,1512754672.794 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-12-08T17:37:52.794Z,1512754672.794 [CTD_Seabird](INFO): Powering down
2017-12-08T17:37:52.826Z,1512754672.826 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-12-08T17:37:52.896Z,1512754672.896 [MissionManager](DEBUG):
2017-12-08T17:37:52.908Z,1512754672.908 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-12-08T17:37:53.017Z,1512754673.017 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-12-08T17:37:53.018Z,1512754673.018 [Default:A.Wait](DEBUG): Construct Wait.
2017-12-08T17:37:53.020Z,1512754673.020 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-12-08T17:37:53.036Z,1512754673.036 [Rowe_600LCM](INFO): LCM OK
2017-12-08T17:37:53.036Z,1512754673.036 [Rowe_600LCM](INFO): Powering up
2017-12-08T17:37:53.055Z,1512754673.055 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-12-08T17:37:53.057Z,1512754673.057 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-12-08T17:37:53.079Z,1512754673.079 [Default:E.Execute](DEBUG): Construct Execute.
2017-12-08T17:37:53.082Z,1512754673.082 [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-08T17:37:53.102Z,1512754673.102 [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-08T17:37:53.138Z,1512754673.138 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-12-08T17:37:53.139Z,1512754673.139 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-12-08T17:37:53.164Z,1512754673.164 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-12-08T17:37:53.483Z,1512754673.483 [ESPComponent](INFO): powering down ESP
2017-12-08T17:37:53.973Z,1512754673.973 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:54.084Z,1512754674.084 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:54.198Z,1512754674.198 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-12-08T17:37:54.205Z,1512754674.205 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-12-08T17:37:54.219Z,1512754674.219 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-12-08T17:37:54.225Z,1512754674.225 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-12-08T17:37:54.235Z,1512754674.235 [MassServo](DEBUG): Initializing EZServoServo.
2017-12-08T17:37:54.241Z,1512754674.241 [MassServo](DEBUG): Initializing MassServo.
2017-12-08T17:37:54.266Z,1512754674.266 [RudderServo](DEBUG): Initializing EZServoServo.
2017-12-08T17:37:54.277Z,1512754674.277 [RudderServo](DEBUG): Initializing RudderServo.
2017-12-08T17:37:54.290Z,1512754674.290 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-12-08T17:37:54.297Z,1512754674.297 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-12-08T17:37:54.523Z,1512754674.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:54.524Z,1512754674.524 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:54.841Z,1512754674.841 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:54.842Z,1512754674.842 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:55.169Z,1512754675.169 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-12-08T17:37:55.169Z,1512754675.169 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-12-08T17:37:55.169Z,1512754675.169 [BuoyancyServo] Communications Fault, FailCount= 1
2017-12-08T17:37:55.169Z,1512754675.169 [BuoyancyServo](ERROR): Communications Fault
2017-12-08T17:37:55.376Z,1512754675.376 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-12-08T17:37:55.433Z,1512754675.433 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:55.434Z,1512754675.434 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:55.446Z,1512754675.446 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-12-08T17:37:55.447Z,1512754675.447 [BuoyancyServo](INFO): Powering down
2017-12-08T17:37:55.860Z,1512754675.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:55.865Z,1512754675.865 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:56.236Z,1512754676.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:56.245Z,1512754676.245 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:56.652Z,1512754676.652 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:56.653Z,1512754676.653 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:57.020Z,1512754677.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:57.022Z,1512754677.022 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-12-08T17:37:57.061Z,1512754677.061 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:57.132Z,1512754677.132 [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-08T17:37:57.283Z,1512754677.283 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-12-08T17:37:57.283Z,1512754677.283 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-12-08T17:37:57.284Z,1512754677.284 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-12-08T17:37:57.445Z,1512754677.445 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:57.446Z,1512754677.446 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:57.510Z,1512754677.510 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-12-08T17:37:57.510Z,1512754677.510 [BuoyancyServo] No Fault, FailCount= 1
2017-12-08T17:37:57.821Z,1512754677.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:57.822Z,1512754677.822 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:57.836Z,1512754677.836 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-12-08T17:37:57.836Z,1512754677.836 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-12-08T17:37:58.249Z,1512754678.249 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:58.250Z,1512754678.250 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:58.677Z,1512754678.677 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:58.679Z,1512754678.679 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:59.050Z,1512754679.050 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:59.051Z,1512754679.051 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:59.429Z,1512754679.429 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:59.430Z,1512754679.430 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:59.825Z,1512754679.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:37:59.826Z,1512754679.826 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:00.269Z,1512754680.269 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:00.270Z,1512754680.270 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:00.586Z,1512754680.586 [AcousticModem_Benthos_ATM900](DEBUG):
2017-12-08T17:38:00.637Z,1512754680.637 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:00.638Z,1512754680.638 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:01.007Z,1512754681.007 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2017-12-08T17:38:01.007Z,1512754681.007 [PNI_TCM] Data Fault, FailCount= 1
2017-12-08T17:38:01.007Z,1512754681.007 [PNI_TCM](ERROR): Data Fault
2017-12-08T17:38:01.030Z,1512754681.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:01.031Z,1512754681.031 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:01.142Z,1512754681.142 [CBIT](ERROR): Data Fault in component: PNI_TCM
2017-12-08T17:38:01.406Z,1512754681.406 [PNI_TCM](INFO): Powering down
2017-12-08T17:38:01.468Z,1512754681.468 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:01.477Z,1512754681.477 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:01.859Z,1512754681.859 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:01.863Z,1512754681.863 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:02.185Z,1512754682.185 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2017-12-08T17:38:02.186Z,1512754682.186 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2017-12-08T17:38:02.187Z,1512754682.187 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2017-12-08T17:38:02.187Z,1512754682.187 [AcousticModem_Benthos_ATM900](DEBUG): Feb 1 2000 23:58:53
2017-12-08T17:38:02.249Z,1512754682.249 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:02.250Z,1512754682.250 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-08T17:38:02.585Z,1512754682.585 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low