2017-09-25T09:06:33.399Z,1506330393.399 [Supervisor](DEBUG): Initializing supervisor. 2017-09-25T09:06:33.401Z,1506330393.401 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-09-25T09:06:33.402Z,1506330393.402 [SyncHandler](INFO): Protected caller Thread ID is 770 2017-09-25T09:06:33.402Z,1506330393.402 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-09-25T09:06:33.403Z,1506330393.403 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-09-25T09:06:33.404Z,1506330393.404 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 771 2017-09-25T09:06:33.406Z,1506330393.406 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-09-25T09:06:33.418Z,1506330393.418 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-09-25T09:06:33.419Z,1506330393.419 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-09-25T09:06:33.420Z,1506330393.420 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 772 2017-09-25T09:06:33.421Z,1506330393.421 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-09-25T09:06:33.422Z,1506330393.422 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-09-25T09:06:33.422Z,1506330393.422 [logger ThreadHandler](INFO): Protected caller Thread ID is 773 2017-09-25T09:06:33.424Z,1506330393.424 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-09-25T09:06:33.425Z,1506330393.425 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-09-25T09:06:33.427Z,1506330393.427 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-09-25T09:06:33.621Z,1506330393.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-09-25T09:06:33.622Z,1506330393.622 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-09-25T09:06:33.758Z,1506330393.758 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-09-25T09:06:33.759Z,1506330393.759 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-09-25T09:06:34.109Z,1506330394.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-09-25T09:06:34.111Z,1506330394.111 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-09-25T09:06:34.254Z,1506330394.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-09-25T09:06:34.255Z,1506330394.255 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-09-25T09:06:34.341Z,1506330394.341 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-09-25T09:06:34.546Z,1506330394.546 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-09-25T09:06:34.547Z,1506330394.547 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-09-25T09:06:34.634Z,1506330394.634 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-09-25T09:06:34.635Z,1506330394.635 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-09-25T09:06:34.929Z,1506330394.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-09-25T09:06:34.930Z,1506330394.930 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-09-25T09:06:35.297Z,1506330395.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-09-25T09:06:35.297Z,1506330395.297 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-09-25T09:06:35.609Z,1506330395.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-09-25T09:06:35.610Z,1506330395.610 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-09-25T09:06:36.127Z,1506330396.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-09-25T09:06:39.561Z,1506330399.561 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-09-25T09:06:39.787Z,1506330399.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-09-25T09:06:39.787Z,1506330399.787 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-09-25T09:06:39.893Z,1506330399.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-09-25T09:06:39.894Z,1506330399.894 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-09-25T09:06:40.361Z,1506330400.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-09-25T09:06:40.362Z,1506330400.362 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-09-25T09:06:40.476Z,1506330400.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-09-25T09:06:40.479Z,1506330400.479 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2017-09-25T09:06:40.481Z,1506330400.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2017-09-25T09:06:40.712Z,1506330400.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-09-25T09:06:40.713Z,1506330400.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2017-09-25T09:06:40.818Z,1506330400.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2017-09-25T09:06:40.928Z,1506330400.928 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2017-09-25T09:06:41.030Z,1506330401.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2017-09-25T09:06:41.122Z,1506330401.122 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2017-09-25T09:06:41.278Z,1506330401.278 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2017-09-25T09:06:44.146Z,1506330404.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2017-09-25T09:06:44.261Z,1506330404.261 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2017-09-25T09:06:44.364Z,1506330404.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2017-09-25T09:06:44.454Z,1506330404.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2017-09-25T09:06:44.627Z,1506330404.627 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-09-25T09:06:44.631Z,1506330404.631 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-09-25T09:06:44.782Z,1506330404.782 [SBIT](DEBUG): Construct Startup Built In Test. 2017-09-25T09:06:44.812Z,1506330404.812 [SBIT] Loaded 2017-09-25T09:06:44.812Z,1506330404.812 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-09-25T09:06:44.813Z,1506330404.813 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-09-25T09:06:44.840Z,1506330404.840 [IBIT] Loaded 2017-09-25T09:06:44.841Z,1506330404.841 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-09-25T09:06:44.844Z,1506330404.844 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-09-25T09:06:44.990Z,1506330404.990 [CBIT] Loaded 2017-09-25T09:06:44.991Z,1506330404.991 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-09-25T09:06:44.991Z,1506330404.991 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-09-25T09:06:44.992Z,1506330404.992 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-09-25T09:06:45.080Z,1506330405.080 [VerticalControl](DEBUG): Construct VerticalControl. 2017-09-25T09:06:45.178Z,1506330405.178 [VerticalControl] Loaded 2017-09-25T09:06:45.178Z,1506330405.178 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-09-25T09:06:45.179Z,1506330405.179 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-09-25T09:06:45.240Z,1506330405.240 [HorizontalControl] Loaded 2017-09-25T09:06:45.240Z,1506330405.240 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-09-25T09:06:45.241Z,1506330405.241 [SpeedControl](DEBUG): Construct SpeedControl. 2017-09-25T09:06:45.247Z,1506330405.247 [SpeedControl] Loaded 2017-09-25T09:06:45.247Z,1506330405.247 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-09-25T09:06:45.248Z,1506330405.248 [BackSeatDriver](DEBUG): Construct BackSeatDriver. 2017-09-25T09:06:45.250Z,1506330405.250 [BackSeatDriver] Loaded 2017-09-25T09:06:45.250Z,1506330405.250 [ComponentRegistry](DEBUG): SyncComponent "BackSeatDriver" handled in the control thread. 2017-09-25T09:06:45.251Z,1506330405.251 [LoopControl](DEBUG): Construct LoopControl. 2017-09-25T09:06:45.251Z,1506330405.251 [LoopControl] Loaded 2017-09-25T09:06:45.252Z,1506330405.252 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-09-25T09:06:45.252Z,1506330405.252 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-09-25T09:06:45.253Z,1506330405.253 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-09-25T09:06:45.292Z,1506330405.292 [DepthRateCalculator] Loaded 2017-09-25T09:06:45.292Z,1506330405.292 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-09-25T09:06:45.297Z,1506330405.297 [PitchRateCalculator] Loaded 2017-09-25T09:06:45.297Z,1506330405.297 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-09-25T09:06:45.313Z,1506330405.313 [SpeedCalculator] Loaded 2017-09-25T09:06:45.313Z,1506330405.313 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-09-25T09:06:45.334Z,1506330405.334 [TempGradientCalculator] Loaded 2017-09-25T09:06:45.334Z,1506330405.334 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-09-25T09:06:45.349Z,1506330405.349 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-09-25T09:06:45.350Z,1506330405.350 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-09-25T09:06:45.355Z,1506330405.355 [YawRateCalculator] Loaded 2017-09-25T09:06:45.355Z,1506330405.355 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-09-25T09:06:45.355Z,1506330405.355 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-09-25T09:06:45.356Z,1506330405.356 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-09-25T09:06:45.473Z,1506330405.473 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-09-25T09:06:45.473Z,1506330405.473 [StratificationFrontDetector](DEBUG): (re)initializing 2017-09-25T09:06:45.473Z,1506330405.473 [StratificationFrontDetector] Loaded 2017-09-25T09:06:45.474Z,1506330405.474 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-09-25T09:06:45.474Z,1506330405.474 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-09-25T09:06:45.475Z,1506330405.475 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-09-25T09:06:45.738Z,1506330405.738 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-09-25T09:06:45.739Z,1506330405.739 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-09-25T09:06:45.855Z,1506330405.855 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-09-25T09:06:45.856Z,1506330405.856 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-09-25T09:06:45.908Z,1506330405.908 [DeadReckonUsingSpeedCalculator] Loaded 2017-09-25T09:06:45.909Z,1506330405.909 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-09-25T09:06:45.962Z,1506330405.962 [DeadReckonWithRespectToSeafloor] Loaded 2017-09-25T09:06:45.962Z,1506330405.962 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2017-09-25T09:06:45.978Z,1506330405.978 [NavChart] Loaded 2017-09-25T09:06:45.978Z,1506330405.978 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-09-25T09:06:45.983Z,1506330405.983 [UniversalFixResidualReporter] Loaded 2017-09-25T09:06:45.983Z,1506330405.983 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-09-25T09:06:45.983Z,1506330405.983 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-09-25T09:06:45.984Z,1506330405.984 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-09-25T09:06:45.993Z,1506330405.993 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-09-25T09:06:45.994Z,1506330405.994 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-09-25T09:06:46.177Z,1506330406.177 [Aanderaa_O2] Loaded 2017-09-25T09:06:46.177Z,1506330406.177 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-09-25T09:06:46.211Z,1506330406.211 [CTD_Seabird] Loaded 2017-09-25T09:06:46.211Z,1506330406.211 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-09-25T09:06:46.213Z,1506330406.213 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0 2017-09-25T09:06:46.213Z,1506330406.213 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 852 2017-09-25T09:06:46.228Z,1506330406.228 [PAR_Licor] Loaded 2017-09-25T09:06:46.228Z,1506330406.228 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-09-25T09:06:46.276Z,1506330406.276 [WetLabsBB2FL] Loaded 2017-09-25T09:06:46.276Z,1506330406.276 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-09-25T09:06:46.277Z,1506330406.277 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0 2017-09-25T09:06:46.277Z,1506330406.277 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 853 2017-09-25T09:06:46.278Z,1506330406.278 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-09-25T09:06:46.279Z,1506330406.279 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-09-25T09:06:48.761Z,1506330408.761 [DataOverHttps] Loaded 2017-09-25T09:06:48.761Z,1506330408.761 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-09-25T09:06:48.775Z,1506330408.775 [Depth_Keller] Loaded 2017-09-25T09:06:48.775Z,1506330408.775 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-09-25T09:06:48.780Z,1506330408.780 [DropWeight] Loaded 2017-09-25T09:06:48.780Z,1506330408.780 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-09-25T09:06:48.868Z,1506330408.868 [NAL9602] Loaded 2017-09-25T09:06:48.868Z,1506330408.868 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-09-25T09:06:48.877Z,1506330408.877 [Onboard] Loaded 2017-09-25T09:06:48.878Z,1506330408.878 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-09-25T09:06:48.884Z,1506330408.884 [Radio_Surface] Loaded 2017-09-25T09:06:48.885Z,1506330408.885 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-09-25T09:06:48.886Z,1506330408.886 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409F54E0 2017-09-25T09:06:48.886Z,1506330408.886 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 854 2017-09-25T09:06:49.077Z,1506330409.077 [PNI_TCM] Loaded 2017-09-25T09:06:49.078Z,1506330409.078 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-09-25T09:06:49.137Z,1506330409.137 [Rowe_600LCM] Loaded 2017-09-25T09:06:49.138Z,1506330409.138 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-09-25T09:06:49.139Z,1506330409.139 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A254E0 2017-09-25T09:06:49.139Z,1506330409.139 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 855 2017-09-25T09:06:50.897Z,1506330410.897 [BPC1] Loaded 2017-09-25T09:06:50.898Z,1506330410.898 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-09-25T09:06:50.898Z,1506330410.898 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-09-25T09:06:50.899Z,1506330410.899 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-09-25T09:06:51.016Z,1506330411.016 [BuoyancyServo] Loaded 2017-09-25T09:06:51.016Z,1506330411.016 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-09-25T09:06:51.028Z,1506330411.028 [ElevatorServo] Loaded 2017-09-25T09:06:51.029Z,1506330411.029 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-09-25T09:06:51.040Z,1506330411.040 [MassServo] Loaded 2017-09-25T09:06:51.040Z,1506330411.040 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-09-25T09:06:51.053Z,1506330411.053 [RudderServo] Loaded 2017-09-25T09:06:51.053Z,1506330411.053 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-09-25T09:06:51.064Z,1506330411.064 [ThrusterServo] Loaded 2017-09-25T09:06:51.064Z,1506330411.064 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-09-25T09:06:51.065Z,1506330411.065 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-09-25T09:06:51.065Z,1506330411.065 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-09-25T09:06:51.192Z,1506330411.192 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-09-25T09:06:51.193Z,1506330411.193 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-09-25T09:06:51.232Z,1506330411.232 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-09-25T09:06:51.236Z,1506330411.236 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-09-25T09:06:51.237Z,1506330411.237 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-09-25T09:06:51.244Z,1506330411.244 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-09-25T09:06:51.245Z,1506330411.245 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD64E0 2017-09-25T09:06:51.245Z,1506330411.245 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 856 2017-09-25T09:06:51.250Z,1506330411.250 [Supervisor](INFO): Main Thread ID is 765 2017-09-25T09:06:51.250Z,1506330411.250 [Supervisor](DEBUG): Running supervisor. 2017-09-25T09:06:51.251Z,1506330411.251 [CommandLine ThreadHandler](INFO): Handler Thread ID is 857 2017-09-25T09:06:51.254Z,1506330411.254 [controlThread ThreadHandler](INFO): Handler Thread ID is 858 2017-09-25T09:06:51.254Z,1506330411.254 [controlThread](DEBUG): Initializing ControlThread 2017-09-25T09:06:51.255Z,1506330411.255 [SBIT](INFO): Initialize SBIT Component. 2017-09-25T09:06:51.255Z,1506330411.255 [SBIT](IMPORTANT): git: 2017-04-25-53-gcaea116 2017-09-25T09:06:51.256Z,1506330411.256 [SBIT](INFO): git hash: caea116986ae94020af0fb1f22ca68d0c0be9268 2017-09-25T09:06:51.257Z,1506330411.257 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-09-25T09:06:51.258Z,1506330411.258 [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-09-25T09:06:51.259Z,1506330411.259 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2017-09-25T09:06:51.259Z,1506330411.259 [IBIT](INFO): Initialize IBIT Component. 2017-09-25T09:06:51.260Z,1506330411.260 [CBIT](DEBUG): Initialize CBIT Component. 2017-09-25T09:06:51.260Z,1506330411.260 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-09-25T09:06:51.261Z,1506330411.261 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2017-09-25T09:06:51.262Z,1506330411.262 [logger ThreadHandler](INFO): Handler Thread ID is 859 2017-09-25T09:06:51.289Z,1506330411.289 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 860 2017-09-25T09:06:51.289Z,1506330411.289 [CTD_Seabird](INFO): Initializing 2017-09-25T09:06:51.333Z,1506330411.333 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 862 2017-09-25T09:06:51.335Z,1506330411.335 [WetLabsBB2FL](INFO): Powering down 2017-09-25T09:06:51.345Z,1506330411.345 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-09-25T09:06:51.347Z,1506330411.347 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-09-25T09:06:51.348Z,1506330411.348 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-09-25T09:06:51.349Z,1506330411.349 [BackSeatDriver](DEBUG): Initialize BackSeatDriver Component. 2017-09-25T09:06:51.439Z,1506330411.439 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 863 2017-09-25T09:06:51.445Z,1506330411.445 [Radio_Surface](INFO): Powering up 2017-09-25T09:06:51.446Z,1506330411.446 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 864 2017-09-25T09:06:51.447Z,1506330411.447 [Rowe_600LCM](INFO): Initializing 2017-09-25T09:06:51.448Z,1506330411.448 [Rowe_600LCM](INFO): Checking LCM 2017-09-25T09:06:51.535Z,1506330411.535 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-09-25T09:06:51.535Z,1506330411.535 [CTD_Seabird](INFO): Powering down 2017-09-25T09:06:51.597Z,1506330411.597 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 865 2017-09-25T09:06:51.607Z,1506330411.607 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-09-25T09:06:51.607Z,1506330411.607 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-09-25T09:06:51.608Z,1506330411.608 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-09-25T09:06:51.608Z,1506330411.608 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-09-25T09:06:51.608Z,1506330411.608 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-09-25T09:06:51.608Z,1506330411.608 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-09-25T09:06:51.608Z,1506330411.608 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-09-25T09:06:51.609Z,1506330411.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-09-25T09:06:51.609Z,1506330411.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-09-25T09:06:51.609Z,1506330411.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-09-25T09:06:51.609Z,1506330411.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-09-25T09:06:51.609Z,1506330411.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-09-25T09:06:51.610Z,1506330411.610 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-09-25T09:06:51.610Z,1506330411.610 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-09-25T09:06:51.610Z,1506330411.610 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-09-25T09:06:51.610Z,1506330411.610 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-09-25T09:06:52.166Z,1506330412.166 [Rowe_600LCM](INFO): LCM OK 2017-09-25T09:06:52.166Z,1506330412.166 [Rowe_600LCM](INFO): Powering up 2017-09-25T09:06:52.713Z,1506330412.713 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-09-25T09:06:52.713Z,1506330412.713 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-09-25T09:06:52.714Z,1506330412.714 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-09-25T09:06:52.714Z,1506330412.714 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-09-25T09:06:52.714Z,1506330412.714 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-09-25T09:06:52.715Z,1506330412.715 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-09-25T09:06:52.716Z,1506330412.716 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-09-25T09:06:52.717Z,1506330412.717 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-09-25T09:06:52.718Z,1506330412.718 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-09-25T09:06:52.718Z,1506330412.718 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-09-25T09:06:52.718Z,1506330412.718 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-09-25T09:06:52.719Z,1506330412.719 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-09-25T09:06:52.719Z,1506330412.719 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-09-25T09:06:52.720Z,1506330412.720 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-09-25T09:06:52.720Z,1506330412.720 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-09-25T09:06:52.721Z,1506330412.721 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-09-25T09:06:52.721Z,1506330412.721 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-09-25T09:06:52.721Z,1506330412.721 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-09-25T09:06:52.752Z,1506330412.752 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-09-25T09:06:52.792Z,1506330412.792 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-09-25T09:06:52.831Z,1506330412.831 [MissionManager](DEBUG): 2017-09-25T09:06:52.832Z,1506330412.832 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-09-25T09:06:52.890Z,1506330412.890 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-09-25T09:06:52.891Z,1506330412.891 [Default:A.Wait](DEBUG): Construct Wait. 2017-09-25T09:06:52.893Z,1506330412.893 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-09-25T09:06:52.927Z,1506330412.927 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-09-25T09:06:52.929Z,1506330412.929 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-09-25T09:06:52.951Z,1506330412.951 [Default:E.Execute](DEBUG): Construct Execute. 2017-09-25T09:06:52.955Z,1506330412.955 [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-09-25T09:06:52.977Z,1506330412.977 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,BackSeatDriver,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-09-25T09:06:53.128Z,1506330413.128 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-09-25T09:06:53.516Z,1506330413.516 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-25T09:06:53.516Z,1506330413.516 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-09-25T09:06:53.656Z,1506330413.656 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-09-25T09:06:53.676Z,1506330413.676 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-25T09:06:53.682Z,1506330413.682 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-09-25T09:06:53.907Z,1506330413.907 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-09-25T09:06:53.913Z,1506330413.913 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-09-25T09:06:53.919Z,1506330413.919 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-09-25T09:06:53.925Z,1506330413.925 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-09-25T09:06:53.931Z,1506330413.931 [MassServo](DEBUG): Initializing EZServoServo. 2017-09-25T09:06:53.937Z,1506330413.937 [MassServo](DEBUG): Initializing MassServo. 2017-09-25T09:06:53.942Z,1506330413.942 [RudderServo](DEBUG): Initializing EZServoServo. 2017-09-25T09:06:53.949Z,1506330413.949 [RudderServo](DEBUG): Initializing RudderServo. 2017-09-25T09:06:53.954Z,1506330413.954 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-25T09:06:53.961Z,1506330413.961 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-25T09:06:54.222Z,1506330414.222 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2017-09-25T09:06:54.222Z,1506330414.222 [DropWeight] Hardware Fault, FailCount= 1 2017-09-25T09:06:54.222Z,1506330414.222 [DropWeight](ERROR): Hardware Fault 2017-09-25T09:06:54.223Z,1506330414.223 [NAL9602](INFO): Powering up NAL9602 2017-09-25T09:06:54.315Z,1506330414.315 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-25T09:06:54.315Z,1506330414.315 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-09-25T09:06:54.316Z,1506330414.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-09-25T09:06:54.317Z,1506330414.317 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-25T09:06:54.319Z,1506330414.319 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-09-25T09:06:54.770Z,1506330414.770 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-09-25T09:06:54.770Z,1506330414.770 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-09-25T09:06:54.770Z,1506330414.770 [BuoyancyServo] Communications Fault, FailCount= 1 2017-09-25T09:06:54.770Z,1506330414.770 [BuoyancyServo](ERROR): Communications Fault 2017-09-25T09:06:54.976Z,1506330414.976 [CommandLine](FAULT): Scheduling is paused 2017-09-25T09:06:54.976Z,1506330414.976 [CBIT](INFO): Critical error at 20170925T090654 2017-09-25T09:06:54.976Z,1506330414.976 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-09-25T09:06:54.979Z,1506330414.979 [CBIT](ERROR): Hardware Fault in component: DropWeight 2017-09-25T09:06:54.979Z,1506330414.979 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2017-09-25T09:06:54.980Z,1506330414.980 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-09-25T09:06:55.050Z,1506330415.050 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-25T09:06:55.050Z,1506330415.050 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-09-25T09:06:55.051Z,1506330415.051 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-09-25T09:06:55.052Z,1506330415.052 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-25T09:06:55.052Z,1506330415.052 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-09-25T09:06:55.171Z,1506330415.171 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-09-25T09:06:55.171Z,1506330415.171 [BuoyancyServo](INFO): Powering down 2017-09-25T09:06:55.238Z,1506330415.238 [CBIT](INFO): Critical error at 20170925T090654 2017-09-25T09:06:55.438Z,1506330415.438 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-25T09:06:55.438Z,1506330415.438 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is