2016-05-10T21:25:58.518Z,1462915558.518 [Supervisor](DEBUG): Initializing supervisor. 2016-05-10T21:25:58.521Z,1462915558.521 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0 2016-05-10T21:25:58.522Z,1462915558.522 [SyncHandler](INFO): Protected caller Thread ID is 766 2016-05-10T21:25:58.522Z,1462915558.522 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2016-05-10T21:25:58.525Z,1462915558.525 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0 2016-05-10T21:25:58.525Z,1462915558.525 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 767 2016-05-10T21:25:58.528Z,1462915558.528 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2016-05-10T21:25:58.540Z,1462915558.540 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2016-05-10T21:25:58.541Z,1462915558.541 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0 2016-05-10T21:25:58.541Z,1462915558.541 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 768 2016-05-10T21:25:58.542Z,1462915558.542 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2016-05-10T21:25:58.545Z,1462915558.545 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0 2016-05-10T21:25:58.546Z,1462915558.546 [logger ThreadHandler](INFO): Protected caller Thread ID is 769 2016-05-10T21:25:58.548Z,1462915558.548 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2016-05-10T21:25:58.548Z,1462915558.548 [Supervisor](INFO): Looking for Config files in directory: Config/ 2016-05-10T21:25:58.552Z,1462915558.552 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2016-05-10T21:25:58.935Z,1462915558.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2016-05-10T21:25:58.937Z,1462915558.937 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2016-05-10T21:25:59.119Z,1462915559.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2016-05-10T21:25:59.119Z,1462915559.119 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2016-05-10T21:25:59.259Z,1462915559.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2016-05-10T21:25:59.260Z,1462915559.260 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2016-05-10T21:25:59.407Z,1462915559.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2016-05-10T21:25:59.409Z,1462915559.409 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2016-05-10T21:25:59.506Z,1462915559.506 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2016-05-10T21:25:59.720Z,1462915559.720 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2016-05-10T21:25:59.721Z,1462915559.721 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2016-05-10T21:25:59.818Z,1462915559.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2016-05-10T21:25:59.820Z,1462915559.820 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2016-05-10T21:26:00.124Z,1462915560.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2016-05-10T21:26:00.125Z,1462915560.125 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2016-05-10T21:26:00.505Z,1462915560.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2016-05-10T21:26:00.506Z,1462915560.506 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2016-05-10T21:26:00.840Z,1462915560.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2016-05-10T21:26:00.841Z,1462915560.841 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2016-05-10T21:26:01.397Z,1462915561.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2016-05-10T21:26:01.398Z,1462915561.398 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2016-05-10T21:26:01.623Z,1462915561.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2016-05-10T21:26:01.624Z,1462915561.624 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2016-05-10T21:26:01.743Z,1462915561.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2016-05-10T21:26:01.743Z,1462915561.743 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2016-05-10T21:26:02.236Z,1462915562.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2016-05-10T21:26:02.237Z,1462915562.237 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2016-05-10T21:26:02.390Z,1462915562.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2016-05-10T21:26:02.392Z,1462915562.392 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2016-05-10T21:26:02.396Z,1462915562.396 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2016-05-10T21:26:02.660Z,1462915562.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2016-05-10T21:26:02.661Z,1462915562.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2016-05-10T21:26:02.793Z,1462915562.793 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2016-05-10T21:26:02.904Z,1462915562.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2016-05-10T21:26:03.009Z,1462915563.009 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2016-05-10T21:26:03.108Z,1462915563.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2016-05-10T21:26:03.264Z,1462915563.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2016-05-10T21:26:03.438Z,1462915563.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2016-05-10T21:26:03.550Z,1462915563.550 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2016-05-10T21:26:03.645Z,1462915563.645 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2016-05-10T21:26:03.746Z,1462915563.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2016-05-10T21:26:03.943Z,1462915563.943 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2016-05-10T21:26:03.945Z,1462915563.945 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2016-05-10T21:26:04.095Z,1462915564.095 [SBIT](DEBUG): Construct Startup Built In Test. 2016-05-10T21:26:04.129Z,1462915564.129 [SBIT] Loaded 2016-05-10T21:26:04.129Z,1462915564.129 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2016-05-10T21:26:04.130Z,1462915564.130 [IBIT](DEBUG): Construct Initiated Built In Test. 2016-05-10T21:26:04.162Z,1462915564.162 [IBIT] Loaded 2016-05-10T21:26:04.162Z,1462915564.162 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2016-05-10T21:26:04.164Z,1462915564.164 [CBIT](DEBUG): Construct Continuous Built In Test. 2016-05-10T21:26:04.314Z,1462915564.314 [CBIT] Loaded 2016-05-10T21:26:04.314Z,1462915564.314 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2016-05-10T21:26:04.315Z,1462915564.315 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2016-05-10T21:26:04.317Z,1462915564.317 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2016-05-10T21:26:04.385Z,1462915564.385 [VerticalControl](DEBUG): Construct VerticalControl. 2016-05-10T21:26:04.497Z,1462915564.497 [VerticalControl] Loaded 2016-05-10T21:26:04.497Z,1462915564.497 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2016-05-10T21:26:04.498Z,1462915564.498 [HorizontalControl](DEBUG): Construct HorizontalControl. 2016-05-10T21:26:04.563Z,1462915564.563 [HorizontalControl] Loaded 2016-05-10T21:26:04.564Z,1462915564.564 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2016-05-10T21:26:04.564Z,1462915564.564 [SpeedControl](DEBUG): Construct SpeedControl. 2016-05-10T21:26:04.570Z,1462915564.570 [SpeedControl] Loaded 2016-05-10T21:26:04.570Z,1462915564.570 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2016-05-10T21:26:04.571Z,1462915564.571 [LoopControl](DEBUG): Construct LoopControl. 2016-05-10T21:26:04.571Z,1462915564.571 [LoopControl] Loaded 2016-05-10T21:26:04.572Z,1462915564.572 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2016-05-10T21:26:04.572Z,1462915564.572 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2016-05-10T21:26:04.573Z,1462915564.573 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2016-05-10T21:26:04.613Z,1462915564.613 [DepthRateCalculator] Loaded 2016-05-10T21:26:04.614Z,1462915564.614 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2016-05-10T21:26:04.619Z,1462915564.619 [PitchRateCalculator] Loaded 2016-05-10T21:26:04.619Z,1462915564.619 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2016-05-10T21:26:04.634Z,1462915564.634 [SpeedCalculator] Loaded 2016-05-10T21:26:04.634Z,1462915564.634 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2016-05-10T21:26:04.650Z,1462915564.650 [TempGradientCalculator] Loaded 2016-05-10T21:26:04.650Z,1462915564.650 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2016-05-10T21:26:04.667Z,1462915564.667 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2016-05-10T21:26:04.667Z,1462915564.667 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2016-05-10T21:26:04.676Z,1462915564.676 [YawRateCalculator] Loaded 2016-05-10T21:26:04.677Z,1462915564.677 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2016-05-10T21:26:04.677Z,1462915564.677 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2016-05-10T21:26:04.678Z,1462915564.678 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2016-05-10T21:26:04.796Z,1462915564.796 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2016-05-10T21:26:04.796Z,1462915564.796 [StratificationFrontDetector](DEBUG): (re)initializing 2016-05-10T21:26:04.796Z,1462915564.796 [StratificationFrontDetector] Loaded 2016-05-10T21:26:04.796Z,1462915564.796 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2016-05-10T21:26:04.797Z,1462915564.797 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2016-05-10T21:26:04.799Z,1462915564.799 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2016-05-10T21:26:05.070Z,1462915565.070 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2016-05-10T21:26:05.071Z,1462915565.071 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2016-05-10T21:26:05.196Z,1462915565.196 [DeadReckonUsingMultipleVelocitySources] Loaded 2016-05-10T21:26:05.196Z,1462915565.196 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2016-05-10T21:26:05.253Z,1462915565.253 [DeadReckonUsingSpeedCalculator] Loaded 2016-05-10T21:26:05.254Z,1462915565.254 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2016-05-10T21:26:05.274Z,1462915565.274 [NavChart] Loaded 2016-05-10T21:26:05.275Z,1462915565.275 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2016-05-10T21:26:05.279Z,1462915565.279 [UniversalFixResidualReporter] Loaded 2016-05-10T21:26:05.279Z,1462915565.279 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2016-05-10T21:26:05.280Z,1462915565.280 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2016-05-10T21:26:05.280Z,1462915565.280 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2016-05-10T21:26:05.289Z,1462915565.289 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2016-05-10T21:26:05.289Z,1462915565.289 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2016-05-10T21:26:05.519Z,1462915565.519 [Aanderaa_O2] Loaded 2016-05-10T21:26:05.519Z,1462915565.519 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2016-05-10T21:26:05.602Z,1462915565.602 [CTD_NeilBrown] Loaded 2016-05-10T21:26:05.602Z,1462915565.602 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2016-05-10T21:26:05.604Z,1462915565.604 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4059E4E0 2016-05-10T21:26:05.604Z,1462915565.604 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 848 2016-05-10T21:26:05.607Z,1462915565.607 [ESPComponent](CRITICAL): No configuration setting for: ESPComponent.espSimulator 2016-05-10T21:26:05.613Z,1462915565.613 [ESPComponent](CRITICAL): No configuration setting for: ESPComponent.stopCompleteTimeout 2016-05-10T21:26:05.629Z,1462915565.629 [ESPComponent] Loaded 2016-05-10T21:26:05.629Z,1462915565.629 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2016-05-10T21:26:05.644Z,1462915565.644 [PAR_Licor] Loaded 2016-05-10T21:26:05.644Z,1462915565.644 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2016-05-10T21:26:05.696Z,1462915565.696 [WetLabsBB2FL] Loaded 2016-05-10T21:26:05.697Z,1462915565.697 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2016-05-10T21:26:05.700Z,1462915565.700 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405CE4E0 2016-05-10T21:26:05.701Z,1462915565.701 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 849 2016-05-10T21:26:05.701Z,1462915565.701 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2016-05-10T21:26:05.702Z,1462915565.702 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2016-05-10T21:26:07.528Z,1462915567.528 [AcousticModem_Benthos_ATM900] Loaded 2016-05-10T21:26:07.528Z,1462915567.528 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2016-05-10T21:26:07.720Z,1462915567.720 [DataOverHttps] Loaded 2016-05-10T21:26:07.720Z,1462915567.720 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2016-05-10T21:26:07.733Z,1462915567.733 [Depth_Keller] Loaded 2016-05-10T21:26:07.734Z,1462915567.734 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2016-05-10T21:26:07.742Z,1462915567.742 [DropWeight] Loaded 2016-05-10T21:26:07.743Z,1462915567.743 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2016-05-10T21:26:07.841Z,1462915567.841 [NAL9602] Loaded 2016-05-10T21:26:07.842Z,1462915567.842 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2016-05-10T21:26:07.891Z,1462915567.891 [Onboard] Loaded 2016-05-10T21:26:07.892Z,1462915567.892 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2016-05-10T21:26:07.908Z,1462915567.908 [Radio_Surface] Loaded 2016-05-10T21:26:07.908Z,1462915567.908 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2016-05-10T21:26:07.910Z,1462915567.910 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0 2016-05-10T21:26:07.910Z,1462915567.910 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 850 2016-05-10T21:26:08.129Z,1462915568.129 [PNI_TCM] Loaded 2016-05-10T21:26:08.130Z,1462915568.130 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2016-05-10T21:26:08.464Z,1462915568.464 [Rowe_600LCM] Loaded 2016-05-10T21:26:08.464Z,1462915568.464 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2016-05-10T21:26:08.468Z,1462915568.468 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0 2016-05-10T21:26:08.469Z,1462915568.469 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 851 2016-05-10T21:26:10.452Z,1462915570.452 [BPC1] Loaded 2016-05-10T21:26:10.453Z,1462915570.453 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2016-05-10T21:26:10.453Z,1462915570.453 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2016-05-10T21:26:10.454Z,1462915570.454 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2016-05-10T21:26:10.582Z,1462915570.582 [BuoyancyServo] Loaded 2016-05-10T21:26:10.582Z,1462915570.582 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2016-05-10T21:26:10.594Z,1462915570.594 [ElevatorServo] Loaded 2016-05-10T21:26:10.594Z,1462915570.594 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2016-05-10T21:26:10.610Z,1462915570.610 [MassServo] Loaded 2016-05-10T21:26:10.610Z,1462915570.610 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2016-05-10T21:26:10.622Z,1462915570.622 [RudderServo] Loaded 2016-05-10T21:26:10.622Z,1462915570.622 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2016-05-10T21:26:10.633Z,1462915570.633 [ThrusterServo] Loaded 2016-05-10T21:26:10.633Z,1462915570.633 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2016-05-10T21:26:10.634Z,1462915570.634 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2016-05-10T21:26:10.635Z,1462915570.635 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2016-05-10T21:26:10.767Z,1462915570.767 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2016-05-10T21:26:10.768Z,1462915570.768 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2016-05-10T21:26:10.813Z,1462915570.813 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2016-05-10T21:26:10.817Z,1462915570.817 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2016-05-10T21:26:10.818Z,1462915570.818 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2016-05-10T21:26:10.824Z,1462915570.824 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2016-05-10T21:26:10.828Z,1462915570.828 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AC34E0 2016-05-10T21:26:10.829Z,1462915570.829 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 852 2016-05-10T21:26:10.833Z,1462915570.833 [Supervisor](INFO): Main Thread ID is 765 2016-05-10T21:26:10.833Z,1462915570.833 [Supervisor](DEBUG): Running supervisor. 2016-05-10T21:26:10.834Z,1462915570.834 [CommandLine ThreadHandler](INFO): Handler Thread ID is 853 2016-05-10T21:26:10.841Z,1462915570.841 [controlThread ThreadHandler](INFO): Handler Thread ID is 854 2016-05-10T21:26:10.841Z,1462915570.841 [controlThread](DEBUG): Initializing ControlThread 2016-05-10T21:26:10.842Z,1462915570.842 [SBIT](INFO): Initialize SBIT Component. 2016-05-10T21:26:10.842Z,1462915570.842 [SBIT](IMPORTANT): git: 2015-11-13-11-g036008f 2016-05-10T21:26:10.843Z,1462915570.843 [SBIT](INFO): git hash: 036008fb27dd1443c9c6898c567944386f491f02 2016-05-10T21:26:10.843Z,1462915570.843 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2016-05-10T21:26:10.843Z,1462915570.843 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 2016-05-10T21:26:10.844Z,1462915570.844 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2016-05-10T21:26:10.845Z,1462915570.845 [IBIT](INFO): Initialize IBIT Component. 2016-05-10T21:26:10.846Z,1462915570.846 [CBIT](DEBUG): Initialize CBIT Component. 2016-05-10T21:26:10.846Z,1462915570.846 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2016-05-10T21:26:10.846Z,1462915570.846 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2016-05-10T21:26:10.849Z,1462915570.849 [logger ThreadHandler](INFO): Handler Thread ID is 855 2016-05-10T21:26:10.872Z,1462915570.872 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2016-05-10T21:26:10.874Z,1462915570.874 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2016-05-10T21:26:10.875Z,1462915570.875 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2016-05-10T21:26:10.875Z,1462915570.875 [LoopControl](DEBUG): Initialize LoopControlComponent. 2016-05-10T21:26:10.876Z,1462915570.876 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2016-05-10T21:26:10.876Z,1462915570.876 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2016-05-10T21:26:10.876Z,1462915570.876 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2016-05-10T21:26:10.877Z,1462915570.877 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2016-05-10T21:26:10.877Z,1462915570.877 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2016-05-10T21:26:10.878Z,1462915570.878 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2016-05-10T21:26:10.878Z,1462915570.878 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-05-10T21:26:10.879Z,1462915570.879 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-05-10T21:26:10.879Z,1462915570.879 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-05-10T21:26:10.880Z,1462915570.880 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-05-10T21:26:10.880Z,1462915570.880 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-05-10T21:26:10.880Z,1462915570.880 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-05-10T21:26:10.881Z,1462915570.881 [NavChart](DEBUG): Initialize NavChart Navigation. 2016-05-10T21:26:10.881Z,1462915570.881 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2016-05-10T21:26:10.901Z,1462915570.901 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2016-05-10T21:26:10.913Z,1462915570.913 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 856 2016-05-10T21:26:10.914Z,1462915570.914 [CTD_NeilBrown](INFO): Powering down 2016-05-10T21:26:10.937Z,1462915570.937 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 857 2016-05-10T21:26:10.939Z,1462915570.939 [WetLabsBB2FL](INFO): Powering down 2016-05-10T21:26:10.969Z,1462915570.969 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2016-05-10T21:26:10.981Z,1462915570.981 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 858 2016-05-10T21:26:10.987Z,1462915570.987 [Radio_Surface](INFO): Powering up 2016-05-10T21:26:10.992Z,1462915570.992 [MissionManager](DEBUG): 2016-05-10T21:26:11.001Z,1462915571.001 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2016-05-10T21:26:11.005Z,1462915571.005 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 859 2016-05-10T21:26:11.005Z,1462915571.005 [Rowe_600LCM](INFO): Powering down 2016-05-10T21:26:11.069Z,1462915571.069 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 861 2016-05-10T21:26:11.075Z,1462915571.075 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources/ElectronicNavigationCharts 2016-05-10T21:26:11.079Z,1462915571.079 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2016-05-10T21:26:11.079Z,1462915571.079 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2016-05-10T21:26:11.080Z,1462915571.080 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2016-05-10T21:26:11.080Z,1462915571.080 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2016-05-10T21:26:11.080Z,1462915571.080 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2016-05-10T21:26:11.080Z,1462915571.080 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2016-05-10T21:26:11.081Z,1462915571.081 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2016-05-10T21:26:11.081Z,1462915571.081 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2016-05-10T21:26:11.147Z,1462915571.147 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2016-05-10T21:26:11.148Z,1462915571.148 [Default:A.Wait](DEBUG): Construct Wait. 2016-05-10T21:26:11.150Z,1462915571.150 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2016-05-10T21:26:11.213Z,1462915571.213 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2016-05-10T21:26:11.215Z,1462915571.215 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2016-05-10T21:26:11.245Z,1462915571.245 [Default:E.Execute](DEBUG): Construct Execute. 2016-05-10T21:26:11.249Z,1462915571.249 [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 2016-05-10T21:26:11.262Z,1462915571.262 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2016-05-10T21:26:11.378Z,1462915571.378 [AcousticModem_Benthos_ATM900](INFO): Powering up 2016-05-10T21:26:11.378Z,1462915571.378 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2016-05-10T21:26:12.040Z,1462915572.040 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:12.123Z,1462915572.123 [Rowe_600LCM](INFO): Initializing 2016-05-10T21:26:12.124Z,1462915572.124 [Rowe_600LCM](IMPORTANT): Checking LCM 2016-05-10T21:26:12.124Z,1462915572.124 [Rowe_600LCM](IMPORTANT): LCM not connected 2016-05-10T21:26:12.124Z,1462915572.124 [Rowe_600LCM](INFO): Powering up 2016-05-10T21:26:12.276Z,1462915572.276 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:12.387Z,1462915572.387 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2016-05-10T21:26:12.395Z,1462915572.395 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2016-05-10T21:26:12.405Z,1462915572.405 [ElevatorServo](DEBUG): Initializing EZServoServo. 2016-05-10T21:26:12.411Z,1462915572.411 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2016-05-10T21:26:12.433Z,1462915572.433 [MassServo](DEBUG): Initializing EZServoServo. 2016-05-10T21:26:12.443Z,1462915572.443 [MassServo](DEBUG): Initializing MassServo. 2016-05-10T21:26:12.457Z,1462915572.457 [RudderServo](DEBUG): Initializing EZServoServo. 2016-05-10T21:26:12.463Z,1462915572.463 [RudderServo](DEBUG): Initializing RudderServo. 2016-05-10T21:26:12.477Z,1462915572.477 [ThrusterServo](DEBUG): Initializing EZServoServo. 2016-05-10T21:26:12.483Z,1462915572.483 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2016-05-10T21:26:12.524Z,1462915572.524 [CommandLine](FAULT): Scheduling is paused 2016-05-10T21:26:12.765Z,1462915572.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:12.766Z,1462915572.766 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:12.990Z,1462915572.990 [NAL9602](INFO): Powering up NAL9602 2016-05-10T21:26:13.043Z,1462915573.043 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:13.044Z,1462915573.044 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:13.392Z,1462915573.392 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2016-05-10T21:26:13.392Z,1462915573.392 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2016-05-10T21:26:13.393Z,1462915573.393 [BuoyancyServo] Communications Fault, FailCount= 1 2016-05-10T21:26:13.393Z,1462915573.393 [BuoyancyServo](ERROR): Communications Fault 2016-05-10T21:26:13.599Z,1462915573.599 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2016-05-10T21:26:13.685Z,1462915573.685 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:13.686Z,1462915573.686 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:13.694Z,1462915573.694 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2016-05-10T21:26:13.696Z,1462915573.696 [BuoyancyServo](INFO): Powering down 2016-05-10T21:26:14.125Z,1462915574.125 [Aanderaa_O2](INFO): Powering down 2016-05-10T21:26:14.298Z,1462915574.298 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:14.299Z,1462915574.299 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:14.446Z,1462915574.446 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:14.447Z,1462915574.447 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:14.878Z,1462915574.878 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:14.879Z,1462915574.879 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:15.231Z,1462915575.231 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:15.232Z,1462915575.232 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:15.662Z,1462915575.662 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:15.676Z,1462915575.676 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:16.080Z,1462915576.080 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:16.081Z,1462915576.081 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:16.232Z,1462915576.232 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2016-05-10T21:26:16.232Z,1462915576.232 [BuoyancyServo] No Fault, FailCount= 1 2016-05-10T21:26:16.454Z,1462915576.454 [Rowe_600LCM](INFO): Stopping previous instance(s) of Rowe LCM interface 2016-05-10T21:26:16.515Z,1462915576.515 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:16.516Z,1462915576.516 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:16.548Z,1462915576.548 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2016-05-10T21:26:16.548Z,1462915576.548 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2016-05-10T21:26:16.631Z,1462915576.631 [Rowe_600LCM](INFO): Starting Rowe LCM interface 2016-05-10T21:26:16.633Z,1462915576.633 [Rowe_600LCM](IMPORTANT): LCM subscribed 2016-05-10T21:26:16.939Z,1462915576.939 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:16.940Z,1462915576.940 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:17.251Z,1462915577.251 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:17.252Z,1462915577.252 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:17.747Z,1462915577.747 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:17.759Z,1462915577.759 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:18.093Z,1462915578.093 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:18.094Z,1462915578.094 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:18.461Z,1462915578.461 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:18.462Z,1462915578.462 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:18.810Z,1462915578.810 [AcousticModem_Benthos_ATM900](DEBUG): 2016-05-10T21:26:18.881Z,1462915578.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:18.882Z,1462915578.882 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:19.258Z,1462915579.258 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:19.259Z,1462915579.259 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:19.663Z,1462915579.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:19.665Z,1462915579.665 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:20.065Z,1462915580.065 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:20.067Z,1462915580.067 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:20.414Z,1462915580.414 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2016-05-10T21:26:20.414Z,1462915580.414 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2016-05-10T21:26:20.415Z,1462915580.415 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2016-05-10T21:26:20.458Z,1462915580.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:20.459Z,1462915580.459 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:20.817Z,1462915580.817 [AcousticModem_Benthos_ATM900](DEBUG): May 10 2016 21:18:51 2016-05-10T21:26:20.851Z,1462915580.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:20.860Z,1462915580.860 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:21.272Z,1462915581.272 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:21.274Z,1462915581.274 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:21.614Z,1462915581.614 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2016-05-10T21:26:21.663Z,1462915581.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:21.664Z,1462915581.664 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:22.011Z,1462915582.011 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2016-05-10T21:26:22.011Z,1462915582.011 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2016-05-10T21:26:22.011Z,1462915582.011 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2016-05-10T21:26:22.043Z,1462915582.043 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:22.044Z,1462915582.044 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:22.411Z,1462915582.411 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2016-05-10T21:26:22.506Z,1462915582.506 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:22.508Z,1462915582.508 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:22.816Z,1462915582.816 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2016-05-10T21:26:22.817Z,1462915582.817 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2016-05-10T21:26:22.850Z,1462915582.850 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:22.852Z,1462915582.852 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:23.217Z,1462915583.217 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2016-05-10T21:26:23.270Z,1462915583.270 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:23.271Z,1462915583.271 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:23.666Z,1462915583.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:23.667Z,1462915583.667 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:24.042Z,1462915584.042 [NAL9602](INFO): NAL9602 initialized 2016-05-10T21:26:24.082Z,1462915584.082 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:24.083Z,1462915584.083 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:24.476Z,1462915584.476 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2016-05-10T21:26:24.477Z,1462915584.477 [PNI_TCM] Data Fault, FailCount= 1 2016-05-10T21:26:24.477Z,1462915584.477 [PNI_TCM](ERROR): Data Fault 2016-05-10T21:26:24.554Z,1462915584.554 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:24.556Z,1462915584.556 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:24.621Z,1462915584.621 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-05-10T21:26:24.873Z,1462915584.873 [PNI_TCM](INFO): Powering down 2016-05-10T21:26:25.066Z,1462915585.066 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:25.068Z,1462915585.068 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:25.297Z,1462915585.297 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:25.298Z,1462915585.298 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:25.693Z,1462915585.693 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:25.694Z,1462915585.694 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:26.113Z,1462915586.113 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:26.114Z,1462915586.114 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:26.172Z,1462915586.172 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-05-10T21:26:26.172Z,1462915586.172 [PNI_TCM] No Fault, FailCount= 1 2016-05-10T21:26:26.477Z,1462915586.477 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:26.479Z,1462915586.479 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:26.897Z,1462915586.897 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:26.898Z,1462915586.898 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:27.331Z,1462915587.331 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:27.332Z,1462915587.332 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:27.720Z,1462915587.720 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:27.721Z,1462915587.721 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:28.116Z,1462915588.116 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:28.117Z,1462915588.117 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:28.499Z,1462915588.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:28.508Z,1462915588.508 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:28.838Z,1462915588.838 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:28.839Z,1462915588.839 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:29.242Z,1462915589.242 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:29.244Z,1462915589.244 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:29.642Z,1462915589.642 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:29.643Z,1462915589.643 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:30.047Z,1462915590.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:30.048Z,1462915590.048 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:30.447Z,1462915590.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:30.448Z,1462915590.448 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:30.848Z,1462915590.848 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:30.849Z,1462915590.849 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:31.289Z,1462915591.289 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:31.290Z,1462915591.290 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:31.638Z,1462915591.638 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:31.639Z,1462915591.639 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:32.030Z,1462915592.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:32.031Z,1462915592.031 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:32.514Z,1462915592.514 [PNI_TCM](ERROR): No parameter response UART error: serial timeout 2016-05-10T21:26:32.514Z,1462915592.514 [PNI_TCM](ERROR): Received 0xF400 2016-05-10T21:26:32.550Z,1462915592.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:32.551Z,1462915592.551 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:32.879Z,1462915592.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:32.880Z,1462915592.880 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:33.382Z,1462915593.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:33.383Z,1462915593.383 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:33.683Z,1462915593.683 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:33.692Z,1462915593.692 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:34.054Z,1462915594.054 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:34.055Z,1462915594.055 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:34.490Z,1462915594.490 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:34.491Z,1462915594.491 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:34.882Z,1462915594.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:34.883Z,1462915594.883 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:35.278Z,1462915595.278 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:35.279Z,1462915595.279 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:35.685Z,1462915595.685 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:35.686Z,1462915595.686 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:36.103Z,1462915596.103 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:36.120Z,1462915596.120 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:36.485Z,1462915596.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:36.487Z,1462915596.487 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:36.893Z,1462915596.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:36.894Z,1462915596.894 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:37.281Z,1462915597.281 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:37.282Z,1462915597.282 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:37.623Z,1462915597.623 [CommandLine](IMPORTANT): got command restart application 2016-05-10T21:26:37.721Z,1462915597.721 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:37.727Z,1462915597.727 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:38.091Z,1462915598.091 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:38.092Z,1462915598.092 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:38.500Z,1462915598.500 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:38.501Z,1462915598.501 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:38.630Z,1462915598.630 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2016-05-10T21:26:38.631Z,1462915598.631 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:38.799Z,1462915598.799 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2016-05-10T21:26:38.800Z,1462915598.800 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:38.800Z,1462915598.800 [CommandLine](INFO): Join timeout helper Thread ID is 877 2016-05-10T21:26:38.828Z,1462915598.828 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2016-05-10T21:26:38.828Z,1462915598.828 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:38.828Z,1462915598.828 [NavChartDb](INFO): Join timeout helper Thread ID is 878 2016-05-10T21:26:38.996Z,1462915598.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:38.997Z,1462915598.997 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:39.290Z,1462915599.290 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:39.291Z,1462915599.291 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:39.683Z,1462915599.683 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:39.684Z,1462915599.684 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:40.126Z,1462915600.126 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:40.127Z,1462915600.127 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:40.269Z,1462915600.269 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2016-05-10T21:26:40.269Z,1462915600.269 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:40.274Z,1462915600.274 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2016-05-10T21:26:40.274Z,1462915600.274 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:40.275Z,1462915600.275 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 879 2016-05-10T21:26:40.454Z,1462915600.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:40.455Z,1462915600.455 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:40.587Z,1462915600.587 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2016-05-10T21:26:40.587Z,1462915600.587 [Rowe_600LCM](INFO): Powering down 2016-05-10T21:26:40.696Z,1462915600.696 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:40.707Z,1462915600.707 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2016-05-10T21:26:40.707Z,1462915600.707 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:40.707Z,1462915600.707 [Radio_Surface](INFO): Join timeout helper Thread ID is 881 2016-05-10T21:26:40.888Z,1462915600.888 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:40.889Z,1462915600.889 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-05-10T21:26:40.915Z,1462915600.915 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2016-05-10T21:26:40.915Z,1462915600.915 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:40.919Z,1462915600.919 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2016-05-10T21:26:40.919Z,1462915600.919 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:40.919Z,1462915600.919 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 882 2016-05-10T21:26:41.071Z,1462915601.071 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2016-05-10T21:26:41.072Z,1462915601.072 [WetLabsBB2FL](INFO): Powering down 2016-05-10T21:26:41.073Z,1462915601.073 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.076Z,1462915601.076 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2016-05-10T21:26:41.076Z,1462915601.076 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.077Z,1462915601.077 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 883 2016-05-10T21:26:41.087Z,1462915601.087 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2016-05-10T21:26:41.088Z,1462915601.088 [CTD_NeilBrown](INFO): Powering down 2016-05-10T21:26:41.089Z,1462915601.089 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.100Z,1462915601.100 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2016-05-10T21:26:41.100Z,1462915601.100 [logger ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.101Z,1462915601.101 [logger](INFO): Join timeout helper Thread ID is 884 2016-05-10T21:26:41.107Z,1462915601.107 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2016-05-10T21:26:41.108Z,1462915601.108 [logger ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.124Z,1462915601.124 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2016-05-10T21:26:41.124Z,1462915601.124 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.124Z,1462915601.124 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2016-05-10T21:26:41.124Z,1462915601.124 [controlThread ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.125Z,1462915601.125 [controlThread](INFO): Join timeout helper Thread ID is 885 2016-05-10T21:26:41.203Z,1462915601.203 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2016-05-10T21:26:41.204Z,1462915601.204 [controlThread](DEBUG): Uninitializing ControlThread 2016-05-10T21:26:41.204Z,1462915601.204 [Aanderaa_O2](INFO): Powering down 2016-05-10T21:26:41.206Z,1462915601.206 [AcousticModem_Benthos_ATM900](INFO): Powering down 2016-05-10T21:26:41.325Z,1462915601.325 [NAL9602](INFO): Powering down 2016-05-10T21:26:41.326Z,1462915601.326 [PNI_TCM](INFO): Powering down 2016-05-10T21:26:41.413Z,1462915601.413 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2016-05-10T21:26:41.414Z,1462915601.414 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2016-05-10T21:26:41.418Z,1462915601.418 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2016-05-10T21:26:41.419Z,1462915601.419 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2016-05-10T21:26:41.419Z,1462915601.419 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2016-05-10T21:26:41.419Z,1462915601.419 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2016-05-10T21:26:41.420Z,1462915601.420 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2016-05-10T21:26:41.420Z,1462915601.420 [BuoyancyServo](INFO): Powering down 2016-05-10T21:26:41.432Z,1462915601.432 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2016-05-10T21:26:41.432Z,1462915601.432 [ElevatorServo](INFO): Powering down 2016-05-10T21:26:41.456Z,1462915601.456 [MassServo](DEBUG): Uninitialize Mass Servo. 2016-05-10T21:26:41.456Z,1462915601.456 [MassServo](INFO): Powering down 2016-05-10T21:26:41.457Z,1462915601.457 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2016-05-10T21:26:41.457Z,1462915601.457 [RudderServo](INFO): Powering down 2016-05-10T21:26:41.458Z,1462915601.458 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2016-05-10T21:26:41.458Z,1462915601.458 [ThrusterServo](INFO): Powering down 2016-05-10T21:26:41.459Z,1462915601.459 [SBIT](DEBUG): Uninitialize SBIT Component. 2016-05-10T21:26:41.459Z,1462915601.459 [IBIT](DEBUG): Uninitialize IBIT Component. 2016-05-10T21:26:41.459Z,1462915601.459 [CBIT](DEBUG): Uninitialize CBIT Component. 2016-05-10T21:26:41.493Z,1462915601.493 [controlThread ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.559Z,1462915601.559 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.564Z,1462915601.564 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.639Z,1462915601.639 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.642Z,1462915601.642 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.813Z,1462915601.813 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-05-10T21:26:41.864Z,1462915601.864 [logger ThreadHandler](INFO): Thread cancelled.