2016-08-27T00:28:34.203Z,1472257714.203 [Supervisor](DEBUG): Initializing supervisor. 2016-08-27T00:28:34.206Z,1472257714.206 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2016-08-27T00:28:34.207Z,1472257714.207 [SyncHandler](INFO): Protected caller Thread ID is 768 2016-08-27T00:28:34.207Z,1472257714.207 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2016-08-27T00:28:34.209Z,1472257714.209 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2016-08-27T00:28:34.210Z,1472257714.210 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 769 2016-08-27T00:28:34.213Z,1472257714.213 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2016-08-27T00:28:34.224Z,1472257714.224 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2016-08-27T00:28:34.225Z,1472257714.225 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2016-08-27T00:28:34.226Z,1472257714.226 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 770 2016-08-27T00:28:34.227Z,1472257714.227 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2016-08-27T00:28:34.229Z,1472257714.229 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2016-08-27T00:28:34.230Z,1472257714.230 [logger ThreadHandler](INFO): Protected caller Thread ID is 771 2016-08-27T00:28:34.232Z,1472257714.232 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2016-08-27T00:28:34.232Z,1472257714.232 [Supervisor](INFO): Looking for Config files in directory: Config/ 2016-08-27T00:28:34.236Z,1472257714.236 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2016-08-27T00:28:34.579Z,1472257714.579 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2016-08-27T00:28:34.580Z,1472257714.580 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2016-08-27T00:28:34.759Z,1472257714.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2016-08-27T00:28:34.761Z,1472257714.761 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2016-08-27T00:28:34.895Z,1472257714.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2016-08-27T00:28:34.896Z,1472257714.896 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2016-08-27T00:28:35.040Z,1472257715.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2016-08-27T00:28:35.040Z,1472257715.040 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2016-08-27T00:28:35.136Z,1472257715.136 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2016-08-27T00:28:35.348Z,1472257715.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2016-08-27T00:28:35.349Z,1472257715.349 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2016-08-27T00:28:35.443Z,1472257715.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2016-08-27T00:28:35.443Z,1472257715.443 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2016-08-27T00:28:35.729Z,1472257715.729 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2016-08-27T00:28:35.731Z,1472257715.731 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2016-08-27T00:28:36.101Z,1472257716.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2016-08-27T00:28:36.102Z,1472257716.102 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2016-08-27T00:28:36.422Z,1472257716.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2016-08-27T00:28:36.422Z,1472257716.422 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2016-08-27T00:28:36.983Z,1472257716.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2016-08-27T00:28:36.984Z,1472257716.984 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2016-08-27T00:28:37.197Z,1472257717.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2016-08-27T00:28:37.198Z,1472257717.198 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2016-08-27T00:28:37.311Z,1472257717.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2016-08-27T00:28:37.312Z,1472257717.312 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2016-08-27T00:28:37.786Z,1472257717.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2016-08-27T00:28:37.787Z,1472257717.787 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2016-08-27T00:28:37.904Z,1472257717.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2016-08-27T00:28:37.906Z,1472257717.906 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2016-08-27T00:28:37.909Z,1472257717.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2016-08-27T00:28:38.164Z,1472257718.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2016-08-27T00:28:38.165Z,1472257718.165 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2016-08-27T00:28:38.287Z,1472257718.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2016-08-27T00:28:38.393Z,1472257718.393 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2016-08-27T00:28:38.497Z,1472257718.497 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2016-08-27T00:28:38.598Z,1472257718.598 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2016-08-27T00:28:38.750Z,1472257718.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2016-08-27T00:28:38.906Z,1472257718.906 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2016-08-27T00:28:39.018Z,1472257719.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2016-08-27T00:28:39.114Z,1472257719.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2016-08-27T00:28:39.213Z,1472257719.213 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2016-08-27T00:28:39.403Z,1472257719.403 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2016-08-27T00:28:39.410Z,1472257719.410 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2016-08-27T00:28:39.566Z,1472257719.566 [SBIT](DEBUG): Construct Startup Built In Test. 2016-08-27T00:28:39.597Z,1472257719.597 [SBIT] Loaded 2016-08-27T00:28:39.597Z,1472257719.597 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2016-08-27T00:28:39.598Z,1472257719.598 [IBIT](DEBUG): Construct Initiated Built In Test. 2016-08-27T00:28:39.630Z,1472257719.630 [IBIT] Loaded 2016-08-27T00:28:39.630Z,1472257719.630 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2016-08-27T00:28:39.633Z,1472257719.633 [CBIT](DEBUG): Construct Continuous Built In Test. 2016-08-27T00:28:39.786Z,1472257719.786 [CBIT] Loaded 2016-08-27T00:28:39.787Z,1472257719.787 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2016-08-27T00:28:39.787Z,1472257719.787 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2016-08-27T00:28:39.788Z,1472257719.788 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2016-08-27T00:28:39.858Z,1472257719.858 [VerticalControl](DEBUG): Construct VerticalControl. 2016-08-27T00:28:39.969Z,1472257719.969 [VerticalControl] Loaded 2016-08-27T00:28:39.970Z,1472257719.970 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2016-08-27T00:28:39.970Z,1472257719.970 [HorizontalControl](DEBUG): Construct HorizontalControl. 2016-08-27T00:28:40.041Z,1472257720.041 [HorizontalControl] Loaded 2016-08-27T00:28:40.041Z,1472257720.041 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2016-08-27T00:28:40.042Z,1472257720.042 [SpeedControl](DEBUG): Construct SpeedControl. 2016-08-27T00:28:40.047Z,1472257720.047 [SpeedControl] Loaded 2016-08-27T00:28:40.048Z,1472257720.048 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2016-08-27T00:28:40.048Z,1472257720.048 [LoopControl](DEBUG): Construct LoopControl. 2016-08-27T00:28:40.049Z,1472257720.049 [LoopControl] Loaded 2016-08-27T00:28:40.049Z,1472257720.049 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2016-08-27T00:28:40.050Z,1472257720.050 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2016-08-27T00:28:40.050Z,1472257720.050 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2016-08-27T00:28:40.092Z,1472257720.092 [DepthRateCalculator] Loaded 2016-08-27T00:28:40.093Z,1472257720.093 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2016-08-27T00:28:40.098Z,1472257720.098 [PitchRateCalculator] Loaded 2016-08-27T00:28:40.098Z,1472257720.098 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2016-08-27T00:28:40.109Z,1472257720.109 [SpeedCalculator] Loaded 2016-08-27T00:28:40.110Z,1472257720.110 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2016-08-27T00:28:40.126Z,1472257720.126 [TempGradientCalculator] Loaded 2016-08-27T00:28:40.127Z,1472257720.127 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2016-08-27T00:28:40.146Z,1472257720.146 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2016-08-27T00:28:40.147Z,1472257720.147 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2016-08-27T00:28:40.152Z,1472257720.152 [YawRateCalculator] Loaded 2016-08-27T00:28:40.152Z,1472257720.152 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2016-08-27T00:28:40.153Z,1472257720.153 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2016-08-27T00:28:40.153Z,1472257720.153 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2016-08-27T00:28:40.274Z,1472257720.274 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2016-08-27T00:28:40.274Z,1472257720.274 [StratificationFrontDetector](DEBUG): (re)initializing 2016-08-27T00:28:40.274Z,1472257720.274 [StratificationFrontDetector] Loaded 2016-08-27T00:28:40.274Z,1472257720.274 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2016-08-27T00:28:40.279Z,1472257720.279 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2016-08-27T00:28:40.280Z,1472257720.280 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2016-08-27T00:28:40.562Z,1472257720.562 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2016-08-27T00:28:40.567Z,1472257720.567 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2016-08-27T00:28:40.693Z,1472257720.693 [DeadReckonUsingMultipleVelocitySources] Loaded 2016-08-27T00:28:40.694Z,1472257720.694 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2016-08-27T00:28:40.752Z,1472257720.752 [DeadReckonUsingSpeedCalculator] Loaded 2016-08-27T00:28:40.753Z,1472257720.753 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2016-08-27T00:28:40.774Z,1472257720.774 [NavChart] Loaded 2016-08-27T00:28:40.774Z,1472257720.774 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2016-08-27T00:28:40.778Z,1472257720.778 [UniversalFixResidualReporter] Loaded 2016-08-27T00:28:40.779Z,1472257720.779 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2016-08-27T00:28:40.779Z,1472257720.779 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2016-08-27T00:28:40.780Z,1472257720.780 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2016-08-27T00:28:40.789Z,1472257720.789 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2016-08-27T00:28:40.790Z,1472257720.790 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2016-08-27T00:28:41.023Z,1472257721.023 [Aanderaa_O2] Loaded 2016-08-27T00:28:41.023Z,1472257721.023 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2016-08-27T00:28:41.107Z,1472257721.107 [CTD_NeilBrown] Loaded 2016-08-27T00:28:41.107Z,1472257721.107 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2016-08-27T00:28:41.112Z,1472257721.112 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4076B4E0 2016-08-27T00:28:41.112Z,1472257721.112 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 850 2016-08-27T00:28:41.129Z,1472257721.129 [ESPComponent] Loaded 2016-08-27T00:28:41.130Z,1472257721.130 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2016-08-27T00:28:41.149Z,1472257721.149 [PAR_Licor] Loaded 2016-08-27T00:28:41.149Z,1472257721.149 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2016-08-27T00:28:41.202Z,1472257721.202 [WetLabsBB2FL] Loaded 2016-08-27T00:28:41.203Z,1472257721.203 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2016-08-27T00:28:41.204Z,1472257721.204 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4079B4E0 2016-08-27T00:28:41.205Z,1472257721.205 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 851 2016-08-27T00:28:41.205Z,1472257721.205 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2016-08-27T00:28:41.206Z,1472257721.206 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2016-08-27T00:28:41.745Z,1472257721.745 [AcousticModem_Benthos_ATM900] Loaded 2016-08-27T00:28:41.745Z,1472257721.745 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2016-08-27T00:28:41.927Z,1472257721.927 [DataOverHttps] Loaded 2016-08-27T00:28:41.927Z,1472257721.927 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2016-08-27T00:28:41.946Z,1472257721.946 [Depth_Keller] Loaded 2016-08-27T00:28:41.946Z,1472257721.946 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2016-08-27T00:28:41.951Z,1472257721.951 [DropWeight] Loaded 2016-08-27T00:28:41.951Z,1472257721.951 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2016-08-27T00:28:42.052Z,1472257722.052 [NAL9602] Loaded 2016-08-27T00:28:42.052Z,1472257722.052 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2016-08-27T00:28:42.099Z,1472257722.099 [Onboard] Loaded 2016-08-27T00:28:42.099Z,1472257722.099 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2016-08-27T00:28:42.107Z,1472257722.107 [Radio_Surface] Loaded 2016-08-27T00:28:42.107Z,1472257722.107 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2016-08-27T00:28:42.114Z,1472257722.114 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E74E0 2016-08-27T00:28:42.114Z,1472257722.114 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 852 2016-08-27T00:28:42.344Z,1472257722.344 [PNI_TCM] Loaded 2016-08-27T00:28:42.344Z,1472257722.344 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2016-08-27T00:28:42.739Z,1472257722.739 [Rowe_600LCM] Loaded 2016-08-27T00:28:42.739Z,1472257722.739 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2016-08-27T00:28:42.743Z,1472257722.743 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0 2016-08-27T00:28:42.743Z,1472257722.743 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 853 2016-08-27T00:28:44.752Z,1472257724.752 [BPC1] Loaded 2016-08-27T00:28:44.753Z,1472257724.753 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2016-08-27T00:28:44.753Z,1472257724.753 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2016-08-27T00:28:44.754Z,1472257724.754 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2016-08-27T00:28:44.889Z,1472257724.889 [BuoyancyServo] Loaded 2016-08-27T00:28:44.889Z,1472257724.889 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2016-08-27T00:28:44.902Z,1472257724.902 [ElevatorServo] Loaded 2016-08-27T00:28:44.902Z,1472257724.902 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2016-08-27T00:28:44.914Z,1472257724.914 [MassServo] Loaded 2016-08-27T00:28:44.914Z,1472257724.914 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2016-08-27T00:28:44.930Z,1472257724.930 [RudderServo] Loaded 2016-08-27T00:28:44.930Z,1472257724.930 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2016-08-27T00:28:44.942Z,1472257724.942 [ThrusterServo] Loaded 2016-08-27T00:28:44.942Z,1472257724.942 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2016-08-27T00:28:44.942Z,1472257724.942 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2016-08-27T00:28:44.943Z,1472257724.943 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2016-08-27T00:28:45.083Z,1472257725.083 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2016-08-27T00:28:45.084Z,1472257725.084 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2016-08-27T00:28:45.128Z,1472257725.128 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2016-08-27T00:28:45.132Z,1472257725.132 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2016-08-27T00:28:45.133Z,1472257725.133 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2016-08-27T00:28:45.140Z,1472257725.140 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2016-08-27T00:28:45.142Z,1472257725.142 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AC54E0 2016-08-27T00:28:45.143Z,1472257725.143 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 854 2016-08-27T00:28:45.147Z,1472257725.147 [Supervisor](INFO): Main Thread ID is 767 2016-08-27T00:28:45.148Z,1472257725.148 [Supervisor](DEBUG): Running supervisor. 2016-08-27T00:28:45.148Z,1472257725.148 [CommandLine ThreadHandler](INFO): Handler Thread ID is 855 2016-08-27T00:28:45.155Z,1472257725.155 [controlThread ThreadHandler](INFO): Handler Thread ID is 856 2016-08-27T00:28:45.155Z,1472257725.155 [controlThread](DEBUG): Initializing ControlThread 2016-08-27T00:28:45.156Z,1472257725.156 [SBIT](INFO): Initialize SBIT Component. 2016-08-27T00:28:45.157Z,1472257725.157 [SBIT](IMPORTANT): git: 2016-07-30 2016-08-27T00:28:45.157Z,1472257725.157 [SBIT](INFO): git hash: 923446e9f5739ffa552978ad226432fb4f934ffd 2016-08-27T00:28:45.157Z,1472257725.157 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2016-08-27T00:28:45.157Z,1472257725.157 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 2016-08-27T00:28:45.158Z,1472257725.158 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2016-08-27T00:28:45.159Z,1472257725.159 [IBIT](INFO): Initialize IBIT Component. 2016-08-27T00:28:45.160Z,1472257725.160 [CBIT](DEBUG): Initialize CBIT Component. 2016-08-27T00:28:45.160Z,1472257725.160 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2016-08-27T00:28:45.160Z,1472257725.160 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2016-08-27T00:28:45.163Z,1472257725.163 [logger ThreadHandler](INFO): Handler Thread ID is 857 2016-08-27T00:28:45.187Z,1472257725.187 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2016-08-27T00:28:45.188Z,1472257725.188 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2016-08-27T00:28:45.189Z,1472257725.189 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2016-08-27T00:28:45.189Z,1472257725.189 [LoopControl](DEBUG): Initialize LoopControlComponent. 2016-08-27T00:28:45.190Z,1472257725.190 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2016-08-27T00:28:45.190Z,1472257725.190 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2016-08-27T00:28:45.190Z,1472257725.190 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2016-08-27T00:28:45.191Z,1472257725.191 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2016-08-27T00:28:45.191Z,1472257725.191 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2016-08-27T00:28:45.192Z,1472257725.192 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2016-08-27T00:28:45.193Z,1472257725.193 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-08-27T00:28:45.193Z,1472257725.193 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:28:45.194Z,1472257725.194 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:28:45.194Z,1472257725.194 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-08-27T00:28:45.195Z,1472257725.195 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:28:45.195Z,1472257725.195 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:28:45.195Z,1472257725.195 [NavChart](DEBUG): Initialize NavChart Navigation. 2016-08-27T00:28:45.196Z,1472257725.196 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2016-08-27T00:28:45.208Z,1472257725.208 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2016-08-27T00:28:45.208Z,1472257725.208 [DropWeight] Hardware Fault, FailCount= 1 2016-08-27T00:28:45.208Z,1472257725.208 [DropWeight](ERROR): Hardware Fault 2016-08-27T00:28:45.215Z,1472257725.215 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 858 2016-08-27T00:28:45.216Z,1472257725.216 [CTD_NeilBrown](INFO): Powering down 2016-08-27T00:28:45.223Z,1472257725.223 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2016-08-27T00:28:45.237Z,1472257725.237 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 859 2016-08-27T00:28:45.239Z,1472257725.239 [WetLabsBB2FL](INFO): Powering down 2016-08-27T00:28:45.274Z,1472257725.274 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 860 2016-08-27T00:28:45.280Z,1472257725.280 [Radio_Surface](INFO): Powering up 2016-08-27T00:28:45.289Z,1472257725.289 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2016-08-27T00:28:45.307Z,1472257725.307 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 861 2016-08-27T00:28:45.308Z,1472257725.308 [Rowe_600LCM](INFO): Initializing 2016-08-27T00:28:45.309Z,1472257725.309 [Rowe_600LCM](INFO): Checking LCM 2016-08-27T00:28:45.309Z,1472257725.309 [Rowe_600LCM](INFO): LCM OK 2016-08-27T00:28:45.309Z,1472257725.309 [Rowe_600LCM](INFO): Powering up 2016-08-27T00:28:45.320Z,1472257725.320 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 862 2016-08-27T00:28:45.333Z,1472257725.333 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2016-08-27T00:28:45.333Z,1472257725.333 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2016-08-27T00:28:45.334Z,1472257725.334 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2016-08-27T00:28:45.334Z,1472257725.334 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2016-08-27T00:28:45.334Z,1472257725.334 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2016-08-27T00:28:45.334Z,1472257725.334 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2016-08-27T00:28:45.334Z,1472257725.334 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2016-08-27T00:28:45.335Z,1472257725.335 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2016-08-27T00:28:45.335Z,1472257725.335 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2016-08-27T00:28:45.335Z,1472257725.335 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2016-08-27T00:28:45.335Z,1472257725.335 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2016-08-27T00:28:45.336Z,1472257725.336 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2016-08-27T00:28:45.336Z,1472257725.336 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2016-08-27T00:28:45.336Z,1472257725.336 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2016-08-27T00:28:45.336Z,1472257725.336 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2016-08-27T00:28:45.336Z,1472257725.336 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2016-08-27T00:28:45.338Z,1472257725.338 [MissionManager](DEBUG): 2016-08-27T00:28:45.351Z,1472257725.351 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2016-08-27T00:28:45.417Z,1472257725.417 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2016-08-27T00:28:45.418Z,1472257725.418 [Default:A.Wait](DEBUG): Construct Wait. 2016-08-27T00:28:45.440Z,1472257725.440 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2016-08-27T00:28:45.471Z,1472257725.471 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2016-08-27T00:28:45.473Z,1472257725.473 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2016-08-27T00:28:45.495Z,1472257725.495 [Default:E.Execute](DEBUG): Construct Execute. 2016-08-27T00:28:45.515Z,1472257725.515 [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-08-27T00:28:45.520Z,1472257725.520 [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-08-27T00:28:45.623Z,1472257725.623 [AcousticModem_Benthos_ATM900](INFO): Powering up 2016-08-27T00:28:45.624Z,1472257725.624 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2016-08-27T00:28:46.154Z,1472257726.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:46.476Z,1472257726.476 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:46.605Z,1472257726.605 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2016-08-27T00:28:46.613Z,1472257726.613 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2016-08-27T00:28:46.624Z,1472257726.624 [ElevatorServo](DEBUG): Initializing EZServoServo. 2016-08-27T00:28:46.633Z,1472257726.633 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2016-08-27T00:28:46.655Z,1472257726.655 [MassServo](DEBUG): Initializing EZServoServo. 2016-08-27T00:28:46.661Z,1472257726.661 [MassServo](DEBUG): Initializing MassServo. 2016-08-27T00:28:46.687Z,1472257726.687 [RudderServo](DEBUG): Initializing EZServoServo. 2016-08-27T00:28:46.693Z,1472257726.693 [RudderServo](DEBUG): Initializing RudderServo. 2016-08-27T00:28:46.699Z,1472257726.699 [ThrusterServo](DEBUG): Initializing EZServoServo. 2016-08-27T00:28:46.705Z,1472257726.705 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2016-08-27T00:28:46.750Z,1472257726.750 [CommandLine](FAULT): Scheduling is paused 2016-08-27T00:28:46.752Z,1472257726.752 [CBIT](ERROR): Hardware Fault in component: DropWeight 2016-08-27T00:28:46.752Z,1472257726.752 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2016-08-27T00:28:47.074Z,1472257727.074 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:47.075Z,1472257727.075 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:47.207Z,1472257727.207 [NAL9602](INFO): Powering up NAL9602 2016-08-27T00:28:47.225Z,1472257727.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:47.234Z,1472257727.234 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:47.574Z,1472257727.574 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2016-08-27T00:28:47.574Z,1472257727.574 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2016-08-27T00:28:47.574Z,1472257727.574 [BuoyancyServo] Communications Fault, FailCount= 1 2016-08-27T00:28:47.574Z,1472257727.574 [BuoyancyServo](ERROR): Communications Fault 2016-08-27T00:28:47.773Z,1472257727.773 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2016-08-27T00:28:47.904Z,1472257727.904 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:47.905Z,1472257727.905 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:47.918Z,1472257727.918 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2016-08-27T00:28:47.931Z,1472257727.931 [BuoyancyServo](INFO): Powering down 2016-08-27T00:28:48.299Z,1472257728.299 [Aanderaa_O2](INFO): Powering down 2016-08-27T00:28:48.425Z,1472257728.425 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:48.427Z,1472257728.427 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:48.667Z,1472257728.667 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:48.684Z,1472257728.684 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:49.016Z,1472257729.016 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:49.024Z,1472257729.024 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:49.458Z,1472257729.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:49.459Z,1472257729.459 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-27T00:28:49.616Z,1472257729.616 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2016-08-27T00:28:49.705Z,1472257729.705 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:./bin/roweadcp -dev /dev/ttyA1 -b 9600 >& /dev/null & 2016-08-27T00:28:50.871Z,1472257730.871 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2016-08-27T00:28:50.873Z,1472257730.873 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2016-08-27T00:28:51.430Z,1472257731.430 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2016-08-27T00:28:51.430Z,1472257731.430 [BuoyancyServo] No Fault, FailCount= 1 2016-08-27T00:28:51.834Z,1472257731.834 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2016-08-27T00:28:51.835Z,1472257731.835 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2016-08-27T00:28:53.212Z,1472257733.212 [AcousticModem_Benthos_ATM900](DEBUG): 2016-08-27T00:28:54.415Z,1472257734.415 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2016-08-27T00:28:54.415Z,1472257734.415 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2016-08-27T00:28:54.416Z,1472257734.416 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2016-08-27T00:28:54.815Z,1472257734.815 [AcousticModem_Benthos_ATM900](DEBUG): Aug 27 2016 00:19:17 2016-08-27T00:28:55.616Z,1472257735.616 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2016-08-27T00:28:56.016Z,1472257736.016 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2016-08-27T00:28:56.413Z,1472257736.413 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2016-08-27T00:28:56.414Z,1472257736.414 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2016-08-27T00:28:56.414Z,1472257736.414 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2016-08-27T00:28:56.814Z,1472257736.814 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2016-08-27T00:28:57.219Z,1472257737.219 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2016-08-27T00:28:57.220Z,1472257737.220 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2016-08-27T00:28:57.616Z,1472257737.616 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2016-08-27T00:28:58.033Z,1472257738.033 [NAL9602](INFO): NAL9602 initialized 2016-08-27T00:29:48.652Z,1472257788.652 [SBIT](IMPORTANT): Beginning Startup BIT 2016-08-27T00:29:48.663Z,1472257788.663 [CBIT](IMPORTANT): Beginning GF scan 2016-08-27T00:30:14.981Z,1472257814.981 [CBIT](IMPORTANT): No ground fault detected 2016-08-27T00:30:35.876Z,1472257835.876 [PNI_TCM](ERROR): CRC does not match. Expected:0x25900 got:0x1347 2016-08-27T00:30:42.279Z,1472257842.279 [PNI_TCM](ERROR): CRC does not match. Expected:0x31771 got:0x28919 2016-08-27T00:30:42.737Z,1472257842.737 [SBIT](IMPORTANT): SBIT PASSED 2016-08-27T00:30:43.101Z,1472257843.101 [MissionManager](IMPORTANT): Started mission Startup 2016-08-27T00:30:43.102Z,1472257843.102 [Startup] Running Loop=1 2016-08-27T00:30:43.102Z,1472257843.102 [Startup](DEBUG): Aggregate::initialize Startup 2016-08-27T00:30:43.102Z,1472257843.102 [Startup:A.GoToSurface] Running Loop=1 2016-08-27T00:30:43.102Z,1472257843.102 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2016-08-27T00:30:43.103Z,1472257843.103 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2016-08-27T00:30:43.104Z,1472257843.104 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2016-08-27T00:30:43.104Z,1472257843.104 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2016-08-27T00:30:43.105Z,1472257843.105 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2016-08-27T00:30:43.106Z,1472257843.106 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2016-08-27T00:30:43.140Z,1472257843.140 [Startup:StartupSatComms] Running Loop=1 2016-08-27T00:30:43.141Z,1472257843.141 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2016-08-27T00:30:43.141Z,1472257843.141 [Startup:StartupSatComms:A] Running Loop=1 2016-08-27T00:30:43.519Z,1472257843.519 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2016-08-27T00:30:45.697Z,1472257845.697 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2016-08-27T00:30:45.697Z,1472257845.697 [Rowe_600LCM] Communications Fault, FailCount= 1 2016-08-27T00:30:45.697Z,1472257845.697 [Rowe_600LCM](ERROR): Communications Fault 2016-08-27T00:30:45.894Z,1472257845.894 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s. 2016-08-27T00:30:45.942Z,1472257845.942 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2016-08-27T00:30:46.102Z,1472257846.102 [Rowe_600LCM](INFO): Powering down 2016-08-27T00:30:46.278Z,1472257846.278 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s. 2016-08-27T00:30:46.689Z,1472257846.689 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2016-08-27T00:30:47.070Z,1472257847.070 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2016-08-27T00:30:47.476Z,1472257847.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2016-08-27T00:30:47.501Z,1472257847.501 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2016-08-27T00:30:47.501Z,1472257847.501 [Rowe_600LCM] No Fault, FailCount= 1 2016-08-27T00:30:47.708Z,1472257847.708 [Rowe_600LCM](INFO): Initializing 2016-08-27T00:30:47.709Z,1472257847.709 [Rowe_600LCM](INFO): Checking LCM 2016-08-27T00:30:47.709Z,1472257847.709 [Rowe_600LCM](INFO): LCM OK 2016-08-27T00:30:47.709Z,1472257847.709 [Rowe_600LCM](INFO): Powering up 2016-08-27T00:30:47.884Z,1472257847.884 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2016-08-27T00:30:48.286Z,1472257848.286 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2016-08-27T00:30:48.676Z,1472257848.676 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2016-08-27T00:30:49.088Z,1472257849.088 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2016-08-27T00:30:49.480Z,1472257849.480 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2016-08-27T00:30:49.901Z,1472257849.901 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2016-08-27T00:30:50.298Z,1472257850.298 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2016-08-27T00:30:50.692Z,1472257850.692 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2016-08-27T00:30:51.088Z,1472257851.088 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2016-08-27T00:30:51.503Z,1472257851.503 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2016-08-27T00:30:51.810Z,1472257851.810 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2016-08-27T00:30:51.941Z,1472257851.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s. 2016-08-27T00:30:51.946Z,1472257851.946 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:./bin/roweadcp -dev /dev/ttyA1 -b 9600 >& /dev/null & 2016-08-27T00:30:51.948Z,1472257851.948 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2016-08-27T00:30:51.950Z,1472257851.950 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2016-08-27T00:30:52.303Z,1472257852.303 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2016-08-27T00:31:02.263Z,1472257862.263 [PNI_TCM](ERROR): CRC does not match. Expected:0x46841 got:0x19483 2016-08-27T00:31:06.276Z,1472257866.276 [PNI_TCM](ERROR): CRC does not match. Expected:0x65247 got:0x0 2016-08-27T00:31:11.083Z,1472257871.083 [PNI_TCM](ERROR): CRC does not match. Expected:0x2720 got:0x1857 2016-08-27T00:31:12.685Z,1472257872.685 [PNI_TCM](ERROR): CRC does not match. Expected:0x56854 got:0x28023 2016-08-27T00:31:17.868Z,1472257877.868 [PNI_TCM](ERROR): CRC does not match. Expected:0x62704 got:0x49198 2016-08-27T00:31:23.872Z,1472257883.872 [PNI_TCM](ERROR): CRC does not match. Expected:0x48771 got:0x1287 2016-08-27T00:31:24.273Z,1472257884.273 [PNI_TCM](ERROR): CRC does not match. Expected:0x27342 got:0x54279 2016-08-27T00:31:43.101Z,1472257903.101 [PNI_TCM](ERROR): CRC does not match. Expected:0x40729 got:0x32467 2016-08-27T00:31:43.478Z,1472257903.478 [Startup:StartupSatComms:A](INFO): Timed out from 2016-08-27T00:30:43.1Z 2016-08-27T00:31:43.478Z,1472257903.478 [Startup:StartupSatComms:A] Stopped 2016-08-27T00:31:43.479Z,1472257903.479 [Startup:StartupSatComms:B] Running Loop=1 2016-08-27T00:31:43.886Z,1472257903.886 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2016-08-27T00:31:45.537Z,1472257905.537 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:31:45.537Z,1472257905.537 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2016-08-27T00:31:45.537Z,1472257905.537 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2016-08-27T00:31:45.552Z,1472257905.552 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:31:45.552Z,1472257905.552 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2016-08-27T00:31:45.552Z,1472257905.552 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2016-08-27T00:31:45.599Z,1472257905.599 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2016-08-27T00:31:45.600Z,1472257905.600 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2016-08-27T00:31:45.957Z,1472257905.957 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2016-08-27T00:31:45.957Z,1472257905.957 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2016-08-27T00:31:45.958Z,1472257905.958 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2016-08-27T00:31:45.958Z,1472257905.958 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2016-08-27T00:31:46.311Z,1472257906.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-08-27T00:31:46.316Z,1472257906.316 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:31:46.316Z,1472257906.316 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:31:46.317Z,1472257906.317 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-08-27T00:31:46.317Z,1472257906.317 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:31:46.318Z,1472257906.318 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:31:49.473Z,1472257909.473 [PNI_TCM](ERROR): CRC does not match. Expected:0x42519 got:0x10741 2016-08-27T00:31:49.875Z,1472257909.875 [PNI_TCM](ERROR): CRC does not match. Expected:0x51842 got:0x16853 2016-08-27T00:31:59.466Z,1472257919.466 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41 2016-08-27T00:32:07.087Z,1472257927.087 [PNI_TCM](ERROR): CRC does not match. Expected:0x27794 got:0x7490 2016-08-27T00:32:13.882Z,1472257933.882 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501 2016-08-27T00:32:20.516Z,1472257940.516 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004706 2016-08-27T00:32:26.482Z,1472257946.482 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20160826T234745/Courier0016.lzma 2016-08-27T00:32:27.275Z,1472257947.275 [DataOverHttps](INFO): Moved sent file to Logs/20160826T234745/Courier0016.lzma.bak 2016-08-27T00:32:27.275Z,1472257947.275 [DataOverHttps](INFO): SBD MOMSN=4425961 2016-08-27T00:32:37.332Z,1472257957.332 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20160827T002834/Courier0000.lzma 2016-08-27T00:32:38.091Z,1472257958.091 [DataOverHttps](INFO): Moved sent file to Logs/20160827T002834/Courier0000.lzma.bak 2016-08-27T00:32:38.092Z,1472257958.092 [DataOverHttps](INFO): SBD MOMSN=4425963 2016-08-27T00:32:43.690Z,1472257963.690 [Startup:StartupSatComms:B](INFO): Timed out from 2016-08-27T00:31:43.5Z 2016-08-27T00:32:43.690Z,1472257963.690 [Startup:StartupSatComms:B] Stopped 2016-08-27T00:32:43.691Z,1472257963.691 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2016-08-27T00:32:43.691Z,1472257963.691 [Startup:StartupSatComms] Stopped 2016-08-27T00:32:43.691Z,1472257963.691 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2016-08-27T00:32:43.692Z,1472257963.692 [Startup](INFO): Completed Startup 2016-08-27T00:32:43.692Z,1472257963.692 [Startup] Stopped 2016-08-27T00:32:43.692Z,1472257963.692 [Startup](DEBUG): Aggregate::uninitialize Startup 2016-08-27T00:32:43.692Z,1472257963.692 [Startup:A.GoToSurface] Stopped 2016-08-27T00:32:43.692Z,1472257963.692 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2016-08-27T00:32:44.040Z,1472257964.040 [MissionManager](IMPORTANT): Started mission Default 2016-08-27T00:32:44.041Z,1472257964.041 [Default] Running Loop=1 2016-08-27T00:32:44.041Z,1472257964.041 [Default](DEBUG): Aggregate::initialize Default 2016-08-27T00:32:44.041Z,1472257964.041 [Default:B.GoToSurface] Running Loop=1 2016-08-27T00:32:44.041Z,1472257964.041 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2016-08-27T00:32:44.042Z,1472257964.042 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2016-08-27T00:32:44.042Z,1472257964.042 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2016-08-27T00:32:44.043Z,1472257964.043 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2016-08-27T00:32:44.043Z,1472257964.043 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2016-08-27T00:32:44.043Z,1472257964.043 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2016-08-27T00:32:44.044Z,1472257964.044 [Default:A.Wait] Running Loop=1 2016-08-27T00:32:44.044Z,1472257964.044 [Default:A.Wait](DEBUG): Initialize Wait Component. 2016-08-27T00:32:48.103Z,1472257968.103 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2016-08-27T00:32:48.103Z,1472257968.103 [Rowe_600LCM] Communications Fault, FailCount= 2 2016-08-27T00:32:48.103Z,1472257968.103 [Rowe_600LCM](ERROR): Communications Fault 2016-08-27T00:32:48.269Z,1472257968.269 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.8 s old, using for 20.0 s. 2016-08-27T00:32:48.287Z,1472257968.287 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2016-08-27T00:32:48.354Z,1472257968.354 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20160826T234745/Express0017.lzma 2016-08-27T00:32:48.432Z,1472257968.432 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2016-08-27T00:32:48.508Z,1472257968.508 [Rowe_600LCM](INFO): Powering down 2016-08-27T00:32:48.781Z,1472257968.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.3 s old, using for 20.0 s. 2016-08-27T00:32:49.103Z,1472257969.103 [DataOverHttps](INFO): Moved sent file to Logs/20160826T234745/Express0017.lzma.bak 2016-08-27T00:32:49.104Z,1472257969.104 [DataOverHttps](INFO): SBD MOMSN=4425969 2016-08-27T00:32:49.183Z,1472257969.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.7 s old, using for 20.0 s. 2016-08-27T00:32:49.537Z,1472257969.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s. 2016-08-27T00:32:49.944Z,1472257969.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s. 2016-08-27T00:32:49.957Z,1472257969.957 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2016-08-27T00:32:49.957Z,1472257969.957 [Rowe_600LCM] No Fault, FailCount= 2 2016-08-27T00:32:50.114Z,1472257970.114 [Rowe_600LCM](INFO): Initializing 2016-08-27T00:32:50.115Z,1472257970.115 [Rowe_600LCM](INFO): Checking LCM 2016-08-27T00:32:50.115Z,1472257970.115 [Rowe_600LCM](INFO): LCM OK 2016-08-27T00:32:50.115Z,1472257970.115 [Rowe_600LCM](INFO): Powering up 2016-08-27T00:32:50.361Z,1472257970.361 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s. 2016-08-27T00:32:50.829Z,1472257970.829 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2016-08-27T00:32:51.138Z,1472257971.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s. 2016-08-27T00:32:51.546Z,1472257971.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.1 s old, using for 20.0 s. 2016-08-27T00:32:52.008Z,1472257972.008 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.5 s old, using for 20.0 s. 2016-08-27T00:32:52.372Z,1472257972.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.9 s old, using for 20.0 s. 2016-08-27T00:32:52.800Z,1472257972.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.3 s old, using for 20.0 s. 2016-08-27T00:32:53.268Z,1472257973.268 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2016-08-27T00:32:53.611Z,1472257973.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.1 s old, using for 20.0 s. 2016-08-27T00:32:53.976Z,1472257973.976 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.5 s old, using for 20.0 s. 2016-08-27T00:32:54.382Z,1472257974.382 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2016-08-27T00:32:54.519Z,1472257974.519 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s. 2016-08-27T00:32:54.616Z,1472257974.616 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:./bin/roweadcp -dev /dev/ttyA1 -b 9600 >& /dev/null & 2016-08-27T00:32:54.618Z,1472257974.618 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2016-08-27T00:32:54.619Z,1472257974.619 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2016-08-27T00:32:54.763Z,1472257974.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.3 s old, using for 20.0 s. 2016-08-27T00:32:57.235Z,1472257977.235 [Default:A.Wait](INFO): Done Waiting. 2016-08-27T00:32:57.236Z,1472257977.236 [Default:A.Wait] Stopped 2016-08-27T00:32:57.236Z,1472257977.236 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2016-08-27T00:32:57.593Z,1472257977.593 [Default:CheckIn] Running Loop=1 2016-08-27T00:32:57.593Z,1472257977.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2016-08-27T00:32:57.593Z,1472257977.593 [Default:CheckIn:Read_GPS] Running Loop=1 2016-08-27T00:32:58.112Z,1472257978.112 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2016-08-27T00:34:01.434Z,1472258041.434 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2016-08-27T00:34:02.717Z,1472258042.717 [PNI_TCM](ERROR): CRC does not match. Expected:0x56731 got:0x17024 2016-08-27T00:34:07.083Z,1472258047.083 [PNI_TCM](ERROR): CRC does not match. Expected:0x19193 got:0x7104 2016-08-27T00:34:18.681Z,1472258058.681 [PNI_TCM](ERROR): CRC does not match. Expected:0x55020 got:0x53211 2016-08-27T00:34:21.092Z,1472258061.092 [BPC1](ERROR): Failed to receive battery data 2016-08-27T00:34:21.092Z,1472258061.092 [BPC1] Communications Fault, FailCount= 1 2016-08-27T00:34:21.092Z,1472258061.092 [BPC1](ERROR): Communications Fault 2016-08-27T00:34:21.139Z,1472258061.139 [CBIT](ERROR): Communications Fault in component: BPC1 2016-08-27T00:34:22.312Z,1472258062.312 [CBIT](INFO): Clearing failed state for component BPC1 2016-08-27T00:34:22.312Z,1472258062.312 [BPC1] No Fault, FailCount= 1 2016-08-27T00:34:29.483Z,1472258069.483 [PNI_TCM](ERROR): CRC does not match. Expected:0x33471 got:0x56537 2016-08-27T00:34:30.275Z,1472258070.275 [PNI_TCM](ERROR): CRC does not match. Expected:0x13056 got:0x0 2016-08-27T00:34:33.461Z,1472258073.461 [PNI_TCM](ERROR): CRC does not match. Expected:0x25615 got:0x49179 2016-08-27T00:34:49.295Z,1472258089.295 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:34:49.295Z,1472258089.295 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2016-08-27T00:34:49.295Z,1472258089.295 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2016-08-27T00:34:49.298Z,1472258089.298 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:34:49.298Z,1472258089.298 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2016-08-27T00:34:49.298Z,1472258089.298 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2016-08-27T00:34:49.345Z,1472258089.345 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2016-08-27T00:34:49.345Z,1472258089.345 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2016-08-27T00:34:49.470Z,1472258089.470 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2016-08-27T00:34:49.471Z,1472258089.471 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2016-08-27T00:34:49.475Z,1472258089.475 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2016-08-27T00:34:49.475Z,1472258089.475 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2016-08-27T00:34:49.887Z,1472258089.887 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-08-27T00:34:49.888Z,1472258089.888 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:34:49.889Z,1472258089.889 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:34:49.889Z,1472258089.889 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-08-27T00:34:49.890Z,1472258089.890 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:34:49.890Z,1472258089.890 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:34:50.673Z,1472258090.673 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2016-08-27T00:34:50.673Z,1472258090.673 [Rowe_600LCM] Communications Fault, FailCount= 3 2016-08-27T00:34:50.674Z,1472258090.674 [Rowe_600LCM](ERROR): Communications Fault 2016-08-27T00:34:50.736Z,1472258090.736 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.5 s old, using for 20.0 s. 2016-08-27T00:34:50.785Z,1472258090.785 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2016-08-27T00:34:51.040Z,1472258091.040 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s. 2016-08-27T00:34:51.078Z,1472258091.078 [Rowe_600LCM](INFO): Powering down 2016-08-27T00:34:51.446Z,1472258091.446 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2016-08-27T00:34:51.808Z,1472258091.808 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2016-08-27T00:34:52.195Z,1472258092.195 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2016-08-27T00:34:52.627Z,1472258092.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2016-08-27T00:34:52.684Z,1472258092.684 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2016-08-27T00:34:52.688Z,1472258092.688 [Rowe_600LCM] No Fault, FailCount= 3 2016-08-27T00:34:52.692Z,1472258092.692 [Rowe_600LCM](INFO): Initializing 2016-08-27T00:34:52.693Z,1472258092.693 [Rowe_600LCM](INFO): Checking LCM 2016-08-27T00:34:52.693Z,1472258092.693 [Rowe_600LCM](INFO): LCM OK 2016-08-27T00:34:52.693Z,1472258092.693 [Rowe_600LCM](INFO): Powering up 2016-08-27T00:34:53.029Z,1472258093.029 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2016-08-27T00:34:53.433Z,1472258093.433 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2016-08-27T00:34:53.796Z,1472258093.796 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2016-08-27T00:34:54.227Z,1472258094.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2016-08-27T00:34:54.607Z,1472258094.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2016-08-27T00:34:55.001Z,1472258095.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2016-08-27T00:34:55.395Z,1472258095.395 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2016-08-27T00:34:55.854Z,1472258095.854 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2016-08-27T00:34:56.217Z,1472258096.217 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2016-08-27T00:34:56.629Z,1472258096.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2016-08-27T00:34:56.910Z,1472258096.910 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2016-08-27T00:34:57.166Z,1472258097.166 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s. 2016-08-27T00:34:57.299Z,1472258097.299 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:./bin/roweadcp -dev /dev/ttyA1 -b 9600 >& /dev/null & 2016-08-27T00:34:57.301Z,1472258097.301 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2016-08-27T00:34:57.302Z,1472258097.302 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2016-08-27T00:34:57.533Z,1472258097.533 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s. 2016-08-27T00:36:52.907Z,1472258212.907 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2016-08-27T00:36:52.907Z,1472258212.907 [Rowe_600LCM] Communications Fault, FailCount= 4 2016-08-27T00:36:52.907Z,1472258212.907 [Rowe_600LCM](ERROR): Communications Fault 2016-08-27T00:36:52.969Z,1472258212.969 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s. 2016-08-27T00:36:52.995Z,1472258212.995 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2016-08-27T00:36:53.312Z,1472258213.312 [Rowe_600LCM](INFO): Powering down 2016-08-27T00:36:53.377Z,1472258213.377 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s. 2016-08-27T00:36:53.735Z,1472258213.735 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.1 s old, using for 20.0 s. 2016-08-27T00:36:54.121Z,1472258214.121 [Rowe_600LCM](FAULT): LCB fault: Software Overcurrent. 2016-08-27T00:36:54.121Z,1472258214.121 [Rowe_600LCM] Hardware Fault, FailCount= 4 2016-08-27T00:36:54.121Z,1472258214.121 [Rowe_600LCM](ERROR): Hardware Fault 2016-08-27T00:36:54.135Z,1472258214.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.5 s old, using for 20.0 s. 2016-08-27T00:36:54.543Z,1472258214.543 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2016-08-27T00:36:54.555Z,1472258214.555 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2016-08-27T00:36:54.555Z,1472258214.555 [Rowe_600LCM] No Fault, FailCount= 4 2016-08-27T00:36:54.926Z,1472258214.926 [Rowe_600LCM](INFO): Initializing 2016-08-27T00:36:54.926Z,1472258214.926 [Rowe_600LCM](INFO): Checking LCM 2016-08-27T00:36:54.926Z,1472258214.926 [Rowe_600LCM](INFO): LCM OK 2016-08-27T00:36:54.926Z,1472258214.926 [Rowe_600LCM](INFO): Powering up 2016-08-27T00:36:54.945Z,1472258214.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2016-08-27T00:36:55.339Z,1472258215.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2016-08-27T00:36:55.739Z,1472258215.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2016-08-27T00:36:56.129Z,1472258216.129 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.5 s old, using for 20.0 s. 2016-08-27T00:36:56.550Z,1472258216.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2016-08-27T00:36:56.950Z,1472258216.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2016-08-27T00:36:57.352Z,1472258217.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2016-08-27T00:36:57.735Z,1472258217.735 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s. 2016-08-27T00:36:58.133Z,1472258218.133 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s. 2016-08-27T00:36:58.546Z,1472258218.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2016-08-27T00:36:58.944Z,1472258218.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2016-08-27T00:36:59.098Z,1472258219.098 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2016-08-27T00:36:59.168Z,1472258219.168 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:./bin/roweadcp -dev /dev/ttyA1 -b 9600 >& /dev/null & 2016-08-27T00:36:59.170Z,1472258219.170 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2016-08-27T00:36:59.171Z,1472258219.171 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2016-08-27T00:36:59.364Z,1472258219.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2016-08-27T00:37:03.351Z,1472258223.351 [BPC1](ERROR): Failed to parse bank A battery data 2016-08-27T00:37:03.352Z,1472258223.352 [BPC1] Data Fault, FailCount= 1 2016-08-27T00:37:03.352Z,1472258223.352 [BPC1](ERROR): Data Fault 2016-08-27T00:37:03.413Z,1472258223.413 [CBIT](ERROR): Data Fault in component: BPC1 2016-08-27T00:37:04.555Z,1472258224.555 [CBIT](INFO): Clearing failed state for component BPC1 2016-08-27T00:37:04.555Z,1472258224.555 [BPC1] No Fault, FailCount= 1 2016-08-27T00:37:50.199Z,1472258270.199 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:37:50.203Z,1472258270.203 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2016-08-27T00:37:50.204Z,1472258270.204 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2016-08-27T00:37:50.206Z,1472258270.206 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:37:50.206Z,1472258270.206 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2016-08-27T00:37:50.206Z,1472258270.206 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2016-08-27T00:37:50.270Z,1472258270.270 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2016-08-27T00:37:50.270Z,1472258270.270 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2016-08-27T00:37:50.489Z,1472258270.489 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2016-08-27T00:37:50.489Z,1472258270.489 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2016-08-27T00:37:50.490Z,1472258270.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2016-08-27T00:37:50.490Z,1472258270.490 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2016-08-27T00:37:50.792Z,1472258270.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-08-27T00:37:50.796Z,1472258270.796 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:37:50.797Z,1472258270.797 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:37:50.797Z,1472258270.797 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-08-27T00:37:50.798Z,1472258270.798 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:37:50.798Z,1472258270.798 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:37:57.968Z,1472258277.968 [Default:CheckIn:Read_GPS](INFO): Timed out from 2016-08-27T00:32:57.6Z 2016-08-27T00:37:57.968Z,1472258277.968 [Default:CheckIn:Read_GPS] Stopped 2016-08-27T00:37:57.968Z,1472258277.968 [Default:CheckIn:Read_Iridium] Running Loop=1 2016-08-27T00:37:58.381Z,1472258278.381 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2016-08-27T00:38:02.984Z,1472258282.984 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20160827T002834/Courier0004.lzma 2016-08-27T00:38:03.744Z,1472258283.744 [DataOverHttps](INFO): Moved sent file to Logs/20160827T002834/Courier0004.lzma.bak 2016-08-27T00:38:03.744Z,1472258283.744 [DataOverHttps](INFO): SBD MOMSN=4426016 2016-08-27T00:38:13.813Z,1472258293.813 [DataOverHttps](INFO): Sending 628 bytes from file Logs/20160827T002834/Express0001.lzma 2016-08-27T00:38:14.577Z,1472258294.577 [DataOverHttps](INFO): Moved sent file to Logs/20160827T002834/Express0001.lzma.bak 2016-08-27T00:38:14.578Z,1472258294.578 [DataOverHttps](INFO): SBD MOMSN=4426018 2016-08-27T00:38:26.760Z,1472258306.760 [DataOverHttps](INFO): Sending 536 bytes from file Logs/20160827T002834/Express0005.lzma 2016-08-27T00:38:27.521Z,1472258307.521 [DataOverHttps](INFO): Moved sent file to Logs/20160827T002834/Express0005.lzma.bak 2016-08-27T00:38:27.521Z,1472258307.521 [DataOverHttps](INFO): SBD MOMSN=4426043 2016-08-27T00:38:27.988Z,1472258307.988 [Default:CheckIn:Read_Iridium] Stopped 2016-08-27T00:38:27.988Z,1472258307.988 [Default:CheckIn:C.Wait] Running Loop=1 2016-08-27T00:38:27.988Z,1472258307.988 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2016-08-27T00:38:45.498Z,1472258325.498 [CBIT](DEBUG): EFC running - data check-sum false 2016-08-27T00:38:55.481Z,1472258335.481 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2016-08-27T00:38:55.481Z,1472258335.481 [Rowe_600LCM] Communications Fault, FailCount= 5 2016-08-27T00:38:55.481Z,1472258335.481 [Rowe_600LCM](ERROR): Communications Fault 2016-08-27T00:38:55.828Z,1472258335.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s. 2016-08-27T00:38:55.843Z,1472258335.843 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2016-08-27T00:38:55.844Z,1472258335.843 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2016-08-27T00:38:55.886Z,1472258335.886 [Rowe_600LCM](INFO): Powering down 2016-08-27T00:38:56.204Z,1472258336.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s. 2016-08-27T00:38:56.635Z,1472258336.635 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2016-08-27T00:38:57.035Z,1472258337.035 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2016-08-27T00:38:57.478Z,1472258337.478 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s. 2016-08-27T00:38:57.800Z,1472258337.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2016-08-27T00:38:58.207Z,1472258338.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2016-08-27T00:38:58.605Z,1472258338.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2016-08-27T00:38:59.074Z,1472258339.074 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2016-08-27T00:38:59.403Z,1472258339.403 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2016-08-27T00:38:59.793Z,1472258339.793 [NAL9602](FAULT): GPS failed to acquire within timeout. 2016-08-27T00:38:59.793Z,1472258339.793 [NAL9602] Data Fault, FailCount= 1 2016-08-27T00:38:59.793Z,1472258339.793 [NAL9602](ERROR): Data Fault 2016-08-27T00:38:59.858Z,1472258339.858 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2016-08-27T00:38:59.880Z,1472258339.880 [CBIT](ERROR): Data Fault in component: NAL9602 2016-08-27T00:39:00.185Z,1472258340.185 [NAL9602](INFO): Powering down 2016-08-27T00:39:00.206Z,1472258340.206 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2016-08-27T00:39:00.646Z,1472258340.646 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2016-08-27T00:39:01.007Z,1472258341.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2016-08-27T00:39:01.020Z,1472258341.020 [CBIT](INFO): Clearing failed state for component NAL9602 2016-08-27T00:39:01.020Z,1472258341.020 [NAL9602] No Fault, FailCount= 1 2016-08-27T00:39:01.451Z,1472258341.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2016-08-27T00:39:01.808Z,1472258341.808 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2016-08-27T00:39:02.206Z,1472258342.206 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2016-08-27T00:39:02.613Z,1472258342.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s. 2016-08-27T00:39:03.002Z,1472258343.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s. 2016-08-27T00:39:03.411Z,1472258343.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2016-08-27T00:39:03.883Z,1472258343.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s. 2016-08-27T00:39:04.253Z,1472258344.253 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2016-08-27T00:39:04.666Z,1472258344.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s. 2016-08-27T00:39:05.017Z,1472258345.017 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s. 2016-08-27T00:39:05.397Z,1472258345.397 [NAL9602](INFO): Powering up NAL9602 2016-08-27T00:39:05.479Z,1472258345.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s. 2016-08-27T00:39:05.843Z,1472258345.843 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s. 2016-08-27T00:39:06.201Z,1472258346.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s. 2016-08-27T00:39:06.602Z,1472258346.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s. 2016-08-27T00:39:07.005Z,1472258347.005 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s. 2016-08-27T00:39:07.409Z,1472258347.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s. 2016-08-27T00:39:07.806Z,1472258347.806 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s. 2016-08-27T00:39:08.203Z,1472258348.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s. 2016-08-27T00:39:08.610Z,1472258348.610 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s. 2016-08-27T00:39:09.015Z,1472258349.015 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s. 2016-08-27T00:39:09.409Z,1472258349.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s. 2016-08-27T00:39:09.809Z,1472258349.809 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s. 2016-08-27T00:39:10.209Z,1472258350.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s. 2016-08-27T00:39:10.608Z,1472258350.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s. 2016-08-27T00:39:11.007Z,1472258351.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s. 2016-08-27T00:39:11.407Z,1472258351.407 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s. 2016-08-27T00:39:11.865Z,1472258351.865 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s. 2016-08-27T00:39:12.221Z,1472258352.221 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s. 2016-08-27T00:39:12.614Z,1472258352.614 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s. 2016-08-27T00:39:13.079Z,1472258353.079 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s. 2016-08-27T00:39:13.412Z,1472258353.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s. 2016-08-27T00:39:13.854Z,1472258353.854 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s. 2016-08-27T00:39:14.213Z,1472258354.213 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s. 2016-08-27T00:39:14.680Z,1472258354.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s. 2016-08-27T00:39:15.076Z,1472258355.076 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s. 2016-08-27T00:39:15.419Z,1472258355.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s. 2016-08-27T00:39:16.583Z,1472258356.583 [NAL9602](INFO): NAL9602 initialized 2016-08-27T00:39:26.227Z,1472258366.227 [BPC1](ERROR): Failed to parse bank A battery data 2016-08-27T00:39:26.227Z,1472258366.227 [BPC1] Data Fault, FailCount= 1 2016-08-27T00:39:26.227Z,1472258366.227 [BPC1](ERROR): Data Fault 2016-08-27T00:39:26.301Z,1472258366.301 [CBIT](ERROR): Data Fault in component: BPC1 2016-08-27T00:39:27.432Z,1472258367.432 [CBIT](INFO): Clearing failed state for component BPC1 2016-08-27T00:39:27.432Z,1472258367.432 [BPC1] No Fault, FailCount= 1 2016-08-27T00:40:11.821Z,1472258411.821 [BPC1](ERROR): Failed to receive battery data 2016-08-27T00:40:11.821Z,1472258411.821 [BPC1] Communications Fault, FailCount= 1 2016-08-27T00:40:11.821Z,1472258411.821 [BPC1](ERROR): Communications Fault 2016-08-27T00:40:11.849Z,1472258411.849 [CBIT](ERROR): Communications Fault in component: BPC1 2016-08-27T00:40:13.036Z,1472258413.036 [CBIT](INFO): Clearing failed state for component BPC1 2016-08-27T00:40:13.036Z,1472258413.036 [BPC1] No Fault, FailCount= 1 2016-08-27T00:40:20.899Z,1472258420.899 [CommandLine](IMPORTANT): got command failComponent 2016-08-27T00:40:20.899Z,1472258420.899 [CommandLine](IMPORTANT): Failed components: 2016-08-27T00:40:20.900Z,1472258420.900 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2016-08-27T00:40:20.900Z,1472258420.900 [CommandLine](IMPORTANT): Rowe_600LCM: Communications Fault 2016-08-27T00:40:29.050Z,1472258429.050 [BPC1](ERROR): Failed to receive battery data 2016-08-27T00:40:29.050Z,1472258429.050 [BPC1] Communications Fault, FailCount= 2 2016-08-27T00:40:29.050Z,1472258429.050 [BPC1](ERROR): Communications Fault 2016-08-27T00:40:29.147Z,1472258429.147 [CBIT](ERROR): Communications Fault in component: BPC1 2016-08-27T00:40:34.067Z,1472258434.067 [CBIT](INFO): Clearing failed state for component BPC1 2016-08-27T00:40:34.067Z,1472258434.067 [BPC1] No Fault, FailCount= 2 2016-08-27T00:40:50.023Z,1472258450.023 [BPC1](ERROR): Failed to receive battery data 2016-08-27T00:40:50.023Z,1472258450.023 [BPC1] Communications Fault, FailCount= 3 2016-08-27T00:40:50.023Z,1472258450.023 [BPC1](ERROR): Communications Fault 2016-08-27T00:40:50.108Z,1472258450.108 [CBIT](ERROR): Communications Fault in component: BPC1 2016-08-27T00:40:50.838Z,1472258450.838 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:40:50.838Z,1472258450.838 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2016-08-27T00:40:50.838Z,1472258450.838 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2016-08-27T00:40:50.840Z,1472258450.840 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:40:50.840Z,1472258450.840 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2016-08-27T00:40:50.840Z,1472258450.840 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2016-08-27T00:40:50.852Z,1472258450.852 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2016-08-27T00:40:50.852Z,1472258450.852 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2016-08-27T00:40:51.256Z,1472258451.256 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2016-08-27T00:40:51.256Z,1472258451.256 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2016-08-27T00:40:51.257Z,1472258451.257 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2016-08-27T00:40:51.257Z,1472258451.257 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2016-08-27T00:40:51.257Z,1472258451.257 [CBIT](INFO): Clearing failed state for component BPC1 2016-08-27T00:40:51.258Z,1472258451.258 [BPC1] No Fault, FailCount= 3 2016-08-27T00:40:51.713Z,1472258451.713 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-08-27T00:40:51.714Z,1472258451.714 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:40:51.717Z,1472258451.717 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:40:51.718Z,1472258451.718 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-08-27T00:40:51.718Z,1472258451.718 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:40:51.719Z,1472258451.719 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:40:52.033Z,1472258452.033 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2016-08-27T00:40:52.425Z,1472258452.425 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2016-08-27T00:40:52.831Z,1472258452.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2016-08-27T00:40:53.230Z,1472258453.230 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2016-08-27T00:40:53.632Z,1472258453.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2016-08-27T00:40:54.070Z,1472258454.070 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2016-08-27T00:40:54.452Z,1472258454.452 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2016-08-27T00:40:54.861Z,1472258454.861 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2016-08-27T00:40:55.241Z,1472258455.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2016-08-27T00:40:55.677Z,1472258455.677 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2016-08-27T00:40:56.052Z,1472258456.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2016-08-27T00:40:56.442Z,1472258456.442 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2016-08-27T00:40:56.855Z,1472258456.855 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2016-08-27T00:40:57.249Z,1472258457.249 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2016-08-27T00:40:57.649Z,1472258457.649 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2016-08-27T00:40:58.045Z,1472258458.045 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s. 2016-08-27T00:40:58.419Z,1472258458.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s. 2016-08-27T00:40:58.852Z,1472258458.852 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2016-08-27T00:40:59.227Z,1472258459.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s. 2016-08-27T00:40:59.666Z,1472258459.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2016-08-27T00:41:00.055Z,1472258460.055 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s. 2016-08-27T00:41:00.503Z,1472258460.503 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s. 2016-08-27T00:41:00.822Z,1472258460.822 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s. 2016-08-27T00:41:01.222Z,1472258461.222 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s. 2016-08-27T00:41:01.617Z,1472258461.617 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s. 2016-08-27T00:41:02.028Z,1472258462.028 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s. 2016-08-27T00:41:02.423Z,1472258462.423 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s. 2016-08-27T00:41:02.882Z,1472258462.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s. 2016-08-27T00:41:03.079Z,1472258463.079 [CommandLine](IMPORTANT): got command failComponent 2016-08-27T00:41:03.079Z,1472258463.079 [CommandLine](IMPORTANT): Failed components: 2016-08-27T00:41:03.079Z,1472258463.079 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2016-08-27T00:41:03.080Z,1472258463.080 [CommandLine](IMPORTANT): Rowe_600LCM: Communications Fault 2016-08-27T00:41:03.336Z,1472258463.336 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s. 2016-08-27T00:41:03.684Z,1472258463.684 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s. 2016-08-27T00:41:04.060Z,1472258464.060 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s. 2016-08-27T00:41:04.412Z,1472258464.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s. 2016-08-27T00:41:04.835Z,1472258464.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s. 2016-08-27T00:41:05.238Z,1472258465.238 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s. 2016-08-27T00:41:05.615Z,1472258465.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s. 2016-08-27T00:41:06.056Z,1472258466.056 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s. 2016-08-27T00:41:06.458Z,1472258466.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s. 2016-08-27T00:41:06.844Z,1472258466.844 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s. 2016-08-27T00:41:07.339Z,1472258467.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s. 2016-08-27T00:41:07.620Z,1472258467.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s. 2016-08-27T00:41:08.024Z,1472258468.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s. 2016-08-27T00:41:08.464Z,1472258468.464 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s. 2016-08-27T00:41:08.870Z,1472258468.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s. 2016-08-27T00:41:09.098Z,1472258469.098 [CommandLine](IMPORTANT): got command failComponent none DropWeight 2016-08-27T00:41:09.098Z,1472258469.098 [DropWeight] No Fault, FailCount= 1 2016-08-27T00:41:09.099Z,1472258469.099 [CommandLine](IMPORTANT): DropWeight failureMode is No Fault 2016-08-27T00:41:09.266Z,1472258469.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s. 2016-08-27T00:41:09.703Z,1472258469.703 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s. 2016-08-27T00:41:10.024Z,1472258470.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s. 2016-08-27T00:41:10.464Z,1472258470.464 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s. 2016-08-27T00:41:29.289Z,1472258489.289 [CommandLine](IMPORTANT): got command failComponent 2016-08-27T00:41:29.289Z,1472258489.289 [CommandLine](IMPORTANT): Failed components: 2016-08-27T00:41:29.290Z,1472258489.290 [CommandLine](IMPORTANT): Rowe_600LCM: Communications Fault 2016-08-27T00:41:34.325Z,1472258494.325 [CommandLine](IMPORTANT): got command failComponent none Rowe_600LCM 2016-08-27T00:41:34.325Z,1472258494.325 [Rowe_600LCM] No Fault, FailCount= 5 2016-08-27T00:41:34.326Z,1472258494.326 [CommandLine](IMPORTANT): Rowe_600LCM failureMode is No Fault 2016-08-27T00:41:34.339Z,1472258494.339 [Rowe_600LCM](INFO): Initializing 2016-08-27T00:41:34.340Z,1472258494.340 [Rowe_600LCM](INFO): Checking LCM 2016-08-27T00:41:34.340Z,1472258494.340 [Rowe_600LCM](INFO): LCM OK 2016-08-27T00:41:34.340Z,1472258494.340 [Rowe_600LCM](INFO): Powering up 2016-08-27T00:41:36.304Z,1472258496.304 [BPC1](ERROR): Failed to parse bank A battery data 2016-08-27T00:41:36.304Z,1472258496.304 [BPC1] Data Fault, FailCount= 1 2016-08-27T00:41:36.304Z,1472258496.304 [BPC1](ERROR): Data Fault 2016-08-27T00:41:36.356Z,1472258496.356 [CBIT](ERROR): Data Fault in component: BPC1 2016-08-27T00:41:37.511Z,1472258497.511 [CBIT](INFO): Clearing failed state for component BPC1 2016-08-27T00:41:37.511Z,1472258497.511 [BPC1] No Fault, FailCount= 1 2016-08-27T00:41:38.513Z,1472258498.513 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2016-08-27T00:41:38.612Z,1472258498.612 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:./bin/roweadcp -dev /dev/ttyA1 -b 9600 >& /dev/null & 2016-08-27T00:41:38.614Z,1472258498.614 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2016-08-27T00:41:38.615Z,1472258498.615 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2016-08-27T00:41:43.185Z,1472258503.185 [CommandLine](IMPORTANT): got command get platform_battery_charge 2016-08-27T00:41:43.186Z,1472258503.186 [CommandLine](IMPORTANT): platform_battery_charge 364.338993 Ah 2016-08-27T00:41:49.488Z,1472258509.488 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501 2016-08-27T00:41:58.066Z,1472258518.066 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml 2016-08-27T00:41:58.066Z,1472258518.066 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml 2016-08-27T00:41:58.218Z,1472258518.218 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool 2016-08-27T00:41:58.223Z,1472258518.223 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg 2016-08-27T00:41:58.236Z,1472258518.236 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg 2016-08-27T00:41:58.308Z,1472258518.308 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool 2016-08-27T00:41:58.316Z,1472258518.316 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool 2016-08-27T00:41:58.320Z,1472258518.320 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool 2016-08-27T00:41:58.365Z,1472258518.365 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 1.000000 bool 2016-08-27T00:41:58.380Z,1472258518.380 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 1.000000 bool 2016-08-27T00:41:58.443Z,1472258518.443 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool 2016-08-27T00:41:58.455Z,1472258518.455 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool 2016-08-27T00:41:58.489Z,1472258518.489 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool 2016-08-27T00:41:58.505Z,1472258518.505 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count 2016-08-27T00:41:58.521Z,1472258518.521 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m 2016-08-27T00:41:58.537Z,1472258518.537 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m 2016-08-27T00:41:58.552Z,1472258518.552 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l 2016-08-27T00:41:58.564Z,1472258518.564 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m 2016-08-27T00:41:58.572Z,1472258518.572 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg 2016-08-27T00:41:58.586Z,1472258518.586 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg 2016-08-27T00:41:58.605Z,1472258518.605 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l 2016-08-27T00:41:58.611Z,1472258518.611 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m 2016-08-27T00:41:58.633Z,1472258518.633 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg 2016-08-27T00:41:58.697Z,1472258518.697 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg 2016-08-27T00:41:58.754Z,1472258518.754 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2016-08-27T00:41:58.791Z,1472258518.791 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2016-08-27T00:41:58.866Z,1472258518.866 [MissionManager](DEBUG): Set either this or PeakDetectNO3Active to True in order to sample Nitrate. EnabledISUS must also be true. Maximum pitch to sample ISUS. (EnabledISUS must also be true.) 60.0 Minimum pitch to sample ISUS. (EnabledISUS must also be true.) -60.0 Turns on peak detection of Cholorphyll. Turns on peak detection of Nitrate (and turns on ISUS). Turns on seawater temperature derivative. Automatically set to True if the Aanderaa O2 sensor is installed. Set to false to disable reading Aandera O2. Automatically set to true if the Neil Brown CTD is enabled. Set to false to disable reading from the Neil Brown. Automatically set to true if the NPS Turbulence sensor is enabled. Set to false to disable reading from the NPS Turbulence sensor. Automatically set to true if the the WetLabs BB2FL is enabled. Set to false to disable the WetLabs BB2FL. Automatically set to True if ISUS is enabled. Set to false to disable ISUS. Low-pass window length (based on depth sensor sampling interval 0.4 second) for low-pass filtering. 20 Shallow depth bound for detecting chl peak on each descent or ascent profile. NaN Deep depth bound for detecting chl peak on each descent or ascent profile. NaN 0 0 NaN NaN 0 0 NaN NaN 2016-08-27T00:41:58.873Z,1472258518.873 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml 2016-08-27T00:41:59.164Z,1472258519.164 [Default] Stopped 2016-08-27T00:41:59.164Z,1472258519.164 [Default](DEBUG): Aggregate::uninitialize Default 2016-08-27T00:41:59.164Z,1472258519.164 [Default:B.GoToSurface] Stopped 2016-08-27T00:41:59.164Z,1472258519.164 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2016-08-27T00:41:59.164Z,1472258519.164 [Default:CheckIn] Stopped 2016-08-27T00:41:59.164Z,1472258519.164 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2016-08-27T00:41:59.165Z,1472258519.165 [Default:CheckIn:C.Wait] Stopped 2016-08-27T00:41:59.165Z,1472258519.165 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2016-08-27T00:41:59.165Z,1472258519.165 [MissionManager](IMPORTANT): Started mission Science 2016-08-27T00:41:59.165Z,1472258519.165 [Science] Running Loop=1 2016-08-27T00:41:59.165Z,1472258519.165 [Science](DEBUG): Aggregate::initialize Science 2016-08-27T00:41:59.165Z,1472258519.165 [Science:A] Running Loop=1 2016-08-27T00:41:59.166Z,1472258519.166 [Science:B] Running Loop=1 2016-08-27T00:41:59.166Z,1472258519.166 [Science:C] Running Loop=1 2016-08-27T00:41:59.166Z,1472258519.166 [Science:D] Running Loop=1 2016-08-27T00:41:59.166Z,1472258519.166 [Science:E] Running Loop=1 2016-08-27T00:41:59.166Z,1472258519.166 [Science:F] Running Loop=1 2016-08-27T00:41:59.166Z,1472258519.166 [Science:PeakDetectChl] Running Loop=1 2016-08-27T00:41:59.166Z,1472258519.166 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl 2016-08-27T00:41:59.166Z,1472258519.166 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2016-08-27T00:41:59.166Z,1472258519.166 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2016-08-27T00:41:59.170Z,1472258519.170 [Science:PeakDetectNO3] Running Loop=1 2016-08-27T00:41:59.170Z,1472258519.170 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3 2016-08-27T00:41:59.170Z,1472258519.170 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2016-08-27T00:41:59.170Z,1472258519.170 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2016-08-27T00:41:59.173Z,1472258519.173 [Science:PeakDetectNO3] Stopped 2016-08-27T00:41:59.173Z,1472258519.173 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3 2016-08-27T00:41:59.173Z,1472258519.173 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2016-08-27T00:41:59.173Z,1472258519.173 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2016-08-27T00:41:59.174Z,1472258519.174 [Science:PeakDetectChl] Stopped 2016-08-27T00:41:59.174Z,1472258519.174 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl 2016-08-27T00:41:59.174Z,1472258519.174 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2016-08-27T00:41:59.174Z,1472258519.174 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2016-08-27T00:41:59.174Z,1472258519.174 [Science:F] Running Loop=1 2016-08-27T00:41:59.175Z,1472258519.175 [Science:F](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2016-08-27T00:41:59.177Z,1472258519.177 [Science:E] Stopped 2016-08-27T00:41:59.177Z,1472258519.177 [Science:D] Stopped 2016-08-27T00:41:59.177Z,1472258519.177 [Science:C] Running Loop=1 2016-08-27T00:41:59.191Z,1472258519.191 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2016-08-27T00:41:59.192Z,1472258519.192 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2016-08-27T00:41:59.198Z,1472258519.198 [Science:B] Stopped 2016-08-27T00:41:59.198Z,1472258519.198 [Science:A] Running Loop=1 2016-08-27T00:41:59.199Z,1472258519.199 [Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water 2016-08-27T00:41:59.226Z,1472258519.226 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2016-08-27T00:41:59.234Z,1472258519.234 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2016-08-27T00:41:59.374Z,1472258519.374 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:41:59.510Z,1472258519.510 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4 2016-08-27T00:41:59.511Z,1472258519.511 [WetLabsBB2FL](INFO): Powering up 2016-08-27T00:41:59.787Z,1472258519.787 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:42:00.191Z,1472258520.191 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:42:00.596Z,1472258520.596 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:42:00.706Z,1472258520.706 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071600 psu 2016-08-27T00:42:00.709Z,1472258520.709 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:00.913Z,1472258520.913 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.066600 psu 2016-08-27T00:42:00.915Z,1472258520.915 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:01.118Z,1472258521.118 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.057500 psu 2016-08-27T00:42:01.119Z,1472258521.119 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:01.318Z,1472258521.318 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.047100 psu 2016-08-27T00:42:01.319Z,1472258521.319 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:01.524Z,1472258521.524 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.039900 psu 2016-08-27T00:42:01.525Z,1472258521.525 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:01.718Z,1472258521.718 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.052500 psu 2016-08-27T00:42:01.720Z,1472258521.720 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:01.919Z,1472258521.919 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.061100 psu 2016-08-27T00:42:01.920Z,1472258521.920 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:02.128Z,1472258522.128 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.068000 psu 2016-08-27T00:42:02.129Z,1472258522.129 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:02.336Z,1472258522.336 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045700 psu 2016-08-27T00:42:02.337Z,1472258522.337 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:02.532Z,1472258522.532 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.049300 psu 2016-08-27T00:42:02.538Z,1472258522.538 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:02.741Z,1472258522.741 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.058600 psu 2016-08-27T00:42:02.742Z,1472258522.742 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:02.944Z,1472258522.944 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.053900 psu 2016-08-27T00:42:02.946Z,1472258522.946 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:03.145Z,1472258523.145 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.043000 psu 2016-08-27T00:42:03.146Z,1472258523.146 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:03.346Z,1472258523.346 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.066900 psu 2016-08-27T00:42:03.352Z,1472258523.352 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:03.549Z,1472258523.549 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.053800 psu 2016-08-27T00:42:03.551Z,1472258523.551 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:03.747Z,1472258523.747 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.005900 psu 2016-08-27T00:42:03.748Z,1472258523.748 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:03.950Z,1472258523.950 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.048200 psu 2016-08-27T00:42:03.952Z,1472258523.952 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:04.159Z,1472258524.159 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.054100 psu 2016-08-27T00:42:04.160Z,1472258524.160 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:04.355Z,1472258524.355 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072400 psu 2016-08-27T00:42:04.356Z,1472258524.356 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:04.430Z,1472258524.430 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:42:04.567Z,1472258524.567 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.059000 psu 2016-08-27T00:42:04.569Z,1472258524.569 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:04.771Z,1472258524.771 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.053900 psu 2016-08-27T00:42:04.772Z,1472258524.772 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:04.974Z,1472258524.974 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.052800 psu 2016-08-27T00:42:04.975Z,1472258524.975 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:05.172Z,1472258525.172 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.040100 psu 2016-08-27T00:42:05.173Z,1472258525.173 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:05.372Z,1472258525.372 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.050800 psu 2016-08-27T00:42:05.373Z,1472258525.373 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:05.577Z,1472258525.577 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.046200 psu 2016-08-27T00:42:05.578Z,1472258525.578 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:05.782Z,1472258525.782 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.060600 psu 2016-08-27T00:42:05.784Z,1472258525.784 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:05.990Z,1472258525.990 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.055800 psu 2016-08-27T00:42:05.991Z,1472258525.991 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:06.193Z,1472258526.193 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.054800 psu 2016-08-27T00:42:06.195Z,1472258526.195 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:06.394Z,1472258526.394 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.037000 psu 2016-08-27T00:42:06.395Z,1472258526.395 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:06.590Z,1472258526.590 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.054100 psu 2016-08-27T00:42:06.592Z,1472258526.592 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:06.792Z,1472258526.792 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075500 psu 2016-08-27T00:42:06.794Z,1472258526.794 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:06.871Z,1472258526.871 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:42:07.004Z,1472258527.004 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.059200 psu 2016-08-27T00:42:07.007Z,1472258527.007 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:07.049Z,1472258527.049 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 223.941 82.400 22.228 30.177 30.177 39.139 8.962 627.4 883.3 24.6 2016-08-27T00:42:07.049Z,1472258527.049 [Aanderaa_O2](DEBUG): Error parsing device response 2016-08-27T00:42:07.207Z,1472258527.207 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.022700 psu 2016-08-27T00:42:07.208Z,1472258527.208 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:07.503Z,1472258527.503 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.047600 psu 2016-08-27T00:42:07.505Z,1472258527.505 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:07.704Z,1472258527.704 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.051300 psu 2016-08-27T00:42:07.705Z,1472258527.705 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:07.908Z,1472258527.908 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045500 psu 2016-08-27T00:42:07.909Z,1472258527.909 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:08.112Z,1472258528.112 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.047900 psu 2016-08-27T00:42:08.114Z,1472258528.114 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:08.251Z,1472258528.251 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 223.963  82.382 22.211 30.182 30.182 39.139 8.957 627.5 882.3 25.2 2016-08-27T00:42:08.251Z,1472258528.251 [Aanderaa_O2](DEBUG): Error parsing device response 2016-08-27T00:42:08.313Z,1472258528.313 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.046200 psu 2016-08-27T00:42:08.314Z,1472258528.314 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:08.376Z,1472258528.376 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:42:08.526Z,1472258528.526 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.026100 psu 2016-08-27T00:42:08.527Z,1472258528.527 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:08.725Z,1472258528.725 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.018200 psu 2016-08-27T00:42:08.727Z,1472258528.727 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:08.926Z,1472258528.926 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.033400 psu 2016-08-27T00:42:08.927Z,1472258528.927 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:09.126Z,1472258529.126 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.009600 psu 2016-08-27T00:42:09.128Z,1472258529.128 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:09.334Z,1472258529.334 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.019500 psu 2016-08-27T00:42:09.335Z,1472258529.335 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:09.450Z,1472258529.450 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 224.844 82.669 22.188 30.146 30.146 39.160 9.013 626.6 881.5 25.9 2016-08-27T00:42:09.451Z,1472258529.451 [Aanderaa_O2](DEBUG): Error parsing device response 2016-08-27T00:42:09.535Z,1472258529.535 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.044700 psu 2016-08-27T00:42:09.542Z,1472258529.542 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:09.742Z,1472258529.742 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.031300 psu 2016-08-27T00:42:09.744Z,1472258529.744 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:09.943Z,1472258529.943 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.038000 psu 2016-08-27T00:42:09.945Z,1472258529.945 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:10.147Z,1472258530.147 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.025000 psu 2016-08-27T00:42:10.149Z,1472258530.149 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:10.352Z,1472258530.352 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.035300 psu 2016-08-27T00:42:10.354Z,1472258530.354 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:10.556Z,1472258530.556 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.018100 psu 2016-08-27T00:42:10.558Z,1472258530.558 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:10.761Z,1472258530.761 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.044300 psu 2016-08-27T00:42:10.762Z,1472258530.762 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:10.961Z,1472258530.961 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.053400 psu 2016-08-27T00:42:10.962Z,1472258530.962 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:11.166Z,1472258531.166 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.050500 psu 2016-08-27T00:42:11.167Z,1472258531.167 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:11.370Z,1472258531.370 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.066800 psu 2016-08-27T00:42:11.372Z,1472258531.372 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:11.445Z,1472258531.445 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 225.325 82.833 22.181 30.125 30.125 39.136 9.011 625.6 880.0 26.2 2016-08-27T00:42:11.445Z,1472258531.445 [Aanderaa_O2](DEBUG): Error parsing device response 2016-08-27T00:42:11.574Z,1472258531.574 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.061300 psu 2016-08-27T00:42:11.575Z,1472258531.575 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:11.779Z,1472258531.779 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.042900 psu 2016-08-27T00:42:11.781Z,1472258531.781 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:11.985Z,1472258531.985 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.039000 psu 2016-08-27T00:42:11.987Z,1472258531.987 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:12.185Z,1472258532.185 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.061300 psu 2016-08-27T00:42:12.187Z,1472258532.187 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:12.395Z,1472258532.395 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.042100 psu 2016-08-27T00:42:12.396Z,1472258532.396 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:12.599Z,1472258532.599 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.063300 psu 2016-08-27T00:42:12.600Z,1472258532.600 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:12.792Z,1472258532.792 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.044300 psu 2016-08-27T00:42:12.793Z,1472258532.793 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:13.005Z,1472258533.005 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.047400 psu 2016-08-27T00:42:13.006Z,1472258533.006 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:13.210Z,1472258533.210 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.038200 psu 2016-08-27T00:42:13.211Z,1472258533.211 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:13.404Z,1472258533.404 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.051400 psu 2016-08-27T00:42:13.406Z,1472258533.406 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:13.713Z,1472258533.713 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.043100 psu 2016-08-27T00:42:13.714Z,1472258533.714 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:13.914Z,1472258533.914 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.017200 psu 2016-08-27T00:42:13.915Z,1472258533.915 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:14.110Z,1472258534.110 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.048900 psu 2016-08-27T00:42:14.111Z,1472258534.111 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:14.321Z,1472258534.321 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.044400 psu 2016-08-27T00:42:14.322Z,1472258534.322 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:14.515Z,1472258534.515 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.039700 psu 2016-08-27T00:42:14.516Z,1472258534.516 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:14.722Z,1472258534.722 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.038900 psu 2016-08-27T00:42:14.724Z,1472258534.724 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:14.932Z,1472258534.932 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.021800 psu 2016-08-27T00:42:14.935Z,1472258534.935 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:15.131Z,1472258535.131 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.005600 psu 2016-08-27T00:42:15.133Z,1472258535.133 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:15.335Z,1472258535.335 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.042900 psu 2016-08-27T00:42:15.337Z,1472258535.337 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:15.536Z,1472258535.536 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.033300 psu 2016-08-27T00:42:15.537Z,1472258535.537 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:15.740Z,1472258535.740 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.039100 psu 2016-08-27T00:42:15.741Z,1472258535.741 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:15.937Z,1472258535.937 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.047700 psu 2016-08-27T00:42:15.938Z,1472258535.938 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:16.145Z,1472258536.145 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.052100 psu 2016-08-27T00:42:16.146Z,1472258536.146 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:16.353Z,1472258536.353 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.049400 psu 2016-08-27T00:42:16.355Z,1472258536.355 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:16.546Z,1472258536.546 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.038500 psu 2016-08-27T00:42:16.547Z,1472258536.547 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:16.754Z,1472258536.754 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.030900 psu 2016-08-27T00:42:16.755Z,1472258536.755 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:16.962Z,1472258536.962 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.049200 psu 2016-08-27T00:42:16.964Z,1472258536.964 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:17.155Z,1472258537.155 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.032900 psu 2016-08-27T00:42:17.156Z,1472258537.156 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:17.359Z,1472258537.359 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.033100 psu 2016-08-27T00:42:17.361Z,1472258537.361 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:17.564Z,1472258537.564 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.038900 psu 2016-08-27T00:42:17.566Z,1472258537.566 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:17.779Z,1472258537.779 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.038700 psu 2016-08-27T00:42:17.781Z,1472258537.781 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:17.980Z,1472258537.980 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.056700 psu 2016-08-27T00:42:17.981Z,1472258537.981 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:18.181Z,1472258538.181 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.049400 psu 2016-08-27T00:42:18.182Z,1472258538.182 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:18.389Z,1472258538.389 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.056100 psu 2016-08-27T00:42:18.390Z,1472258538.390 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:18.582Z,1472258538.582 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.062300 psu 2016-08-27T00:42:18.583Z,1472258538.583 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:18.770Z,1472258538.770 [CommandLine](IMPORTANT): got command show variable chlorophyll 2016-08-27T00:42:18.779Z,1472258538.779 [CommandLine](IMPORTANT): mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter) 2016-08-27T00:42:18.789Z,1472258538.789 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.035900 psu 2016-08-27T00:42:18.791Z,1472258538.791 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:18.989Z,1472258538.989 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.059700 psu 2016-08-27T00:42:18.993Z,1472258538.993 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:19.191Z,1472258539.191 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.066600 psu 2016-08-27T00:42:19.193Z,1472258539.193 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:19.339Z,1472258539.339 [CommandLine](IMPORTANT): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter) 2016-08-27T00:42:19.339Z,1472258539.339 [CommandLine](IMPORTANT): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter) 2016-08-27T00:42:19.340Z,1472258539.340 [CommandLine](IMPORTANT): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter) 2016-08-27T00:42:19.357Z,1472258539.357 [CommandLine](IMPORTANT): WetLabsBB2FL.mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter) 2016-08-27T00:42:19.401Z,1472258539.401 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.051100 psu 2016-08-27T00:42:19.402Z,1472258539.402 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:19.598Z,1472258539.598 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.048300 psu 2016-08-27T00:42:19.600Z,1472258539.600 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:19.810Z,1472258539.810 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.060600 psu 2016-08-27T00:42:19.812Z,1472258539.812 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:20.103Z,1472258540.103 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.048600 psu 2016-08-27T00:42:20.104Z,1472258540.104 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:20.313Z,1472258540.313 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.057100 psu 2016-08-27T00:42:20.315Z,1472258540.315 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:20.512Z,1472258540.512 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045900 psu 2016-08-27T00:42:20.514Z,1472258540.514 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:20.712Z,1472258540.712 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.062500 psu 2016-08-27T00:42:20.713Z,1472258540.713 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:20.920Z,1472258540.920 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.059700 psu 2016-08-27T00:42:20.921Z,1472258540.921 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:21.124Z,1472258541.124 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083900 psu 2016-08-27T00:42:21.126Z,1472258541.126 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:21.325Z,1472258541.325 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081400 psu 2016-08-27T00:42:21.327Z,1472258541.327 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:21.525Z,1472258541.525 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.068500 psu 2016-08-27T00:42:21.526Z,1472258541.526 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:21.746Z,1472258541.746 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.036700 psu 2016-08-27T00:42:21.747Z,1472258541.747 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:21.937Z,1472258541.937 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.052100 psu 2016-08-27T00:42:21.938Z,1472258541.938 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:22.142Z,1472258542.142 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.047700 psu 2016-08-27T00:42:22.143Z,1472258542.143 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:22.339Z,1472258542.339 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.032800 psu 2016-08-27T00:42:22.340Z,1472258542.340 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:22.547Z,1472258542.547 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.046100 psu 2016-08-27T00:42:22.548Z,1472258542.548 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:22.751Z,1472258542.751 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.041100 psu 2016-08-27T00:42:22.752Z,1472258542.752 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:22.956Z,1472258542.956 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.039700 psu 2016-08-27T00:42:22.957Z,1472258542.957 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:23.149Z,1472258543.149 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045100 psu 2016-08-27T00:42:23.150Z,1472258543.150 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:23.352Z,1472258543.352 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.041700 psu 2016-08-27T00:42:23.354Z,1472258543.354 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:23.566Z,1472258543.566 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.049500 psu 2016-08-27T00:42:23.569Z,1472258543.569 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:23.765Z,1472258543.765 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.044100 psu 2016-08-27T00:42:23.767Z,1472258543.767 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:23.970Z,1472258543.970 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.046100 psu 2016-08-27T00:42:23.974Z,1472258543.974 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:24.173Z,1472258544.173 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045100 psu 2016-08-27T00:42:24.175Z,1472258544.175 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:24.367Z,1472258544.367 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.049300 psu 2016-08-27T00:42:24.370Z,1472258544.370 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:24.441Z,1472258544.441 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:42:24.571Z,1472258544.571 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.051500 psu 2016-08-27T00:42:24.573Z,1472258544.573 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:24.775Z,1472258544.775 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.044900 psu 2016-08-27T00:42:24.777Z,1472258544.777 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:24.983Z,1472258544.983 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.049300 psu 2016-08-27T00:42:24.984Z,1472258544.984 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:25.179Z,1472258545.179 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.040400 psu 2016-08-27T00:42:25.181Z,1472258545.181 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:25.278Z,1472258545.278 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:42:25.391Z,1472258545.391 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045500 psu 2016-08-27T00:42:25.393Z,1472258545.393 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:25.596Z,1472258545.596 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.056200 psu 2016-08-27T00:42:25.597Z,1472258545.597 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:25.796Z,1472258545.796 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.003900 psu 2016-08-27T00:42:25.797Z,1472258545.797 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:26.001Z,1472258546.001 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.035200 psu 2016-08-27T00:42:26.003Z,1472258546.003 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:26.008Z,1472258546.008 [CommandLine](IMPORTANT): got command stop 2016-08-27T00:42:26.008Z,1472258546.008 [CommandLine](IMPORTANT): Scheduling is paused 2016-08-27T00:42:26.026Z,1472258546.026 [Science] Stopped 2016-08-27T00:42:26.026Z,1472258546.026 [Science](DEBUG): Aggregate::uninitialize Science 2016-08-27T00:42:26.026Z,1472258546.026 [Science:A] Stopped 2016-08-27T00:42:26.026Z,1472258546.026 [Science:C] Stopped 2016-08-27T00:42:26.026Z,1472258546.026 [Science:F] Stopped 2016-08-27T00:42:26.205Z,1472258546.205 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.034100 psu 2016-08-27T00:42:26.206Z,1472258546.206 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:42:26.210Z,1472258546.210 [WetLabsBB2FL](INFO): Powering down 2016-08-27T00:42:26.446Z,1472258546.446 [MissionManager](IMPORTANT): Started mission Default 2016-08-27T00:42:26.446Z,1472258546.446 [Default] Running Loop=1 2016-08-27T00:42:26.446Z,1472258546.446 [Default](DEBUG): Aggregate::initialize Default 2016-08-27T00:42:26.446Z,1472258546.446 [Default:B.GoToSurface] Running Loop=1 2016-08-27T00:42:26.446Z,1472258546.446 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2016-08-27T00:42:26.447Z,1472258546.447 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2016-08-27T00:42:26.447Z,1472258546.447 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2016-08-27T00:42:26.452Z,1472258546.452 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2016-08-27T00:42:26.452Z,1472258546.452 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2016-08-27T00:42:26.453Z,1472258546.453 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2016-08-27T00:42:26.453Z,1472258546.453 [Default:A.Wait] Running Loop=1 2016-08-27T00:42:26.453Z,1472258546.453 [Default:A.Wait](DEBUG): Initialize Wait Component. 2016-08-27T00:42:26.789Z,1472258546.789 [Aanderaa_O2](INFO): Powering down 2016-08-27T00:42:34.145Z,1472258554.145 [CommandLine](IMPORTANT): got command show variable chlorophyll 2016-08-27T00:42:34.149Z,1472258554.149 [CommandLine](IMPORTANT): mass_concentration_of_chlorophyll_in_sea_water (unknown) 2016-08-27T00:42:34.366Z,1472258554.366 [CommandLine](IMPORTANT): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter) 2016-08-27T00:42:34.367Z,1472258554.367 [CommandLine](IMPORTANT): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter) 2016-08-27T00:42:34.369Z,1472258554.369 [CommandLine](IMPORTANT): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter) 2016-08-27T00:42:34.370Z,1472258554.370 [CommandLine](IMPORTANT): WetLabsBB2FL.mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter) 2016-08-27T00:42:39.806Z,1472258559.806 [Default:A.Wait](INFO): Done Waiting. 2016-08-27T00:42:39.806Z,1472258559.806 [Default:A.Wait] Stopped 2016-08-27T00:42:39.807Z,1472258559.807 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2016-08-27T00:42:40.168Z,1472258560.168 [Default:CheckIn] Running Loop=1 2016-08-27T00:42:40.168Z,1472258560.168 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2016-08-27T00:42:40.168Z,1472258560.168 [Default:CheckIn:Read_GPS] Running Loop=1 2016-08-27T00:42:44.326Z,1472258564.326 [CommandLine](IMPORTANT): got command report touch WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2016-08-27T00:43:42.119Z,1472258622.119 [CommandLine](IMPORTANT): got command report 2016-08-27T00:43:42.119Z,1472258622.119 [Reporter](INFO): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water reporting when touched 2016-08-27T00:43:52.028Z,1472258632.028 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:43:52.028Z,1472258632.028 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2016-08-27T00:43:52.029Z,1472258632.029 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2016-08-27T00:43:52.031Z,1472258632.031 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:43:52.031Z,1472258632.031 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2016-08-27T00:43:52.031Z,1472258632.031 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2016-08-27T00:43:52.073Z,1472258632.073 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2016-08-27T00:43:52.074Z,1472258632.074 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2016-08-27T00:43:52.482Z,1472258632.482 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2016-08-27T00:43:52.482Z,1472258632.482 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2016-08-27T00:43:52.482Z,1472258632.482 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2016-08-27T00:43:52.482Z,1472258632.482 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2016-08-27T00:43:52.849Z,1472258632.849 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-08-27T00:43:52.850Z,1472258632.850 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:43:52.850Z,1472258632.850 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:43:52.851Z,1472258632.851 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-08-27T00:43:52.851Z,1472258632.851 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:43:52.852Z,1472258632.852 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:44:12.025Z,1472258652.025 [BPC1](ERROR): Failed to parse bank B battery data 2016-08-27T00:44:12.026Z,1472258652.026 [BPC1] Data Fault, FailCount= 1 2016-08-27T00:44:12.026Z,1472258652.026 [BPC1](ERROR): Data Fault 2016-08-27T00:44:12.080Z,1472258652.080 [CBIT](ERROR): Data Fault in component: BPC1 2016-08-27T00:44:13.271Z,1472258653.271 [CBIT](INFO): Clearing failed state for component BPC1 2016-08-27T00:44:13.271Z,1472258653.271 [BPC1] No Fault, FailCount= 1 2016-08-27T00:45:06.797Z,1472258706.797 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml 2016-08-27T00:45:06.798Z,1472258706.798 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml 2016-08-27T00:45:06.967Z,1472258706.967 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool 2016-08-27T00:45:06.974Z,1472258706.974 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg 2016-08-27T00:45:07.001Z,1472258707.001 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg 2016-08-27T00:45:07.018Z,1472258707.018 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool 2016-08-27T00:45:07.021Z,1472258707.021 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool 2016-08-27T00:45:07.023Z,1472258707.023 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool 2016-08-27T00:45:07.035Z,1472258707.035 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 1.000000 bool 2016-08-27T00:45:07.043Z,1472258707.043 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 1.000000 bool 2016-08-27T00:45:07.047Z,1472258707.047 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool 2016-08-27T00:45:07.059Z,1472258707.059 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool 2016-08-27T00:45:07.071Z,1472258707.071 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool 2016-08-27T00:45:07.078Z,1472258707.078 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count 2016-08-27T00:45:07.080Z,1472258707.080 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m 2016-08-27T00:45:07.091Z,1472258707.091 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m 2016-08-27T00:45:07.098Z,1472258707.098 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l 2016-08-27T00:45:07.105Z,1472258707.105 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m 2016-08-27T00:45:07.108Z,1472258707.108 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg 2016-08-27T00:45:07.119Z,1472258707.119 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg 2016-08-27T00:45:07.126Z,1472258707.126 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l 2016-08-27T00:45:07.130Z,1472258707.130 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m 2016-08-27T00:45:07.133Z,1472258707.133 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg 2016-08-27T00:45:07.136Z,1472258707.136 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg 2016-08-27T00:45:07.177Z,1472258707.177 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2016-08-27T00:45:07.212Z,1472258707.212 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2016-08-27T00:45:07.267Z,1472258707.267 [MissionManager](DEBUG): Set either this or PeakDetectNO3Active to True in order to sample Nitrate. EnabledISUS must also be true. Maximum pitch to sample ISUS. (EnabledISUS must also be true.) 60.0 Minimum pitch to sample ISUS. (EnabledISUS must also be true.) -60.0 Turns on peak detection of Cholorphyll. Turns on peak detection of Nitrate (and turns on ISUS). Turns on seawater temperature derivative. Automatically set to True if the Aanderaa O2 sensor is installed. Set to false to disable reading Aandera O2. Automatically set to true if the Neil Brown CTD is enabled. Set to false to disable reading from the Neil Brown. Automatically set to true if the NPS Turbulence sensor is enabled. Set to false to disable reading from the NPS Turbulence sensor. Automatically set to true if the the WetLabs BB2FL is enabled. Set to false to disable the WetLabs BB2FL. Automatically set to True if ISUS is enabled. Set to false to disable ISUS. Low-pass window length (based on depth sensor sampling interval 0.4 second) for low-pass filtering. 20 Shallow depth bound for detecting chl peak on each descent or ascent profile. NaN Deep depth bound for detecting chl peak on each descent or ascent profile. NaN 0 0 NaN NaN 0 0 NaN NaN 2016-08-27T00:45:07.274Z,1472258707.274 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml 2016-08-27T00:45:07.402Z,1472258707.402 [Default] Stopped 2016-08-27T00:45:07.402Z,1472258707.402 [Default](DEBUG): Aggregate::uninitialize Default 2016-08-27T00:45:07.402Z,1472258707.402 [Default:B.GoToSurface] Stopped 2016-08-27T00:45:07.402Z,1472258707.402 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2016-08-27T00:45:07.402Z,1472258707.402 [Default:CheckIn] Stopped 2016-08-27T00:45:07.403Z,1472258707.403 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2016-08-27T00:45:07.403Z,1472258707.403 [Default:CheckIn:Read_GPS] Stopped 2016-08-27T00:45:07.403Z,1472258707.403 [MissionManager](IMPORTANT): Started mission Science 2016-08-27T00:45:07.403Z,1472258707.403 [Science] Running Loop=1 2016-08-27T00:45:07.403Z,1472258707.403 [Science](DEBUG): Aggregate::initialize Science 2016-08-27T00:45:07.404Z,1472258707.404 [Science:A] Running Loop=1 2016-08-27T00:45:07.404Z,1472258707.404 [Science:B] Running Loop=1 2016-08-27T00:45:07.404Z,1472258707.404 [Science:C] Running Loop=1 2016-08-27T00:45:07.404Z,1472258707.404 [Science:D] Running Loop=1 2016-08-27T00:45:07.404Z,1472258707.404 [Science:E] Running Loop=1 2016-08-27T00:45:07.404Z,1472258707.404 [Science:F] Running Loop=1 2016-08-27T00:45:07.404Z,1472258707.404 [Science:PeakDetectChl] Running Loop=1 2016-08-27T00:45:07.404Z,1472258707.404 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl 2016-08-27T00:45:07.405Z,1472258707.405 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2016-08-27T00:45:07.405Z,1472258707.405 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2016-08-27T00:45:07.411Z,1472258707.411 [Science:PeakDetectNO3] Running Loop=1 2016-08-27T00:45:07.411Z,1472258707.411 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3 2016-08-27T00:45:07.411Z,1472258707.411 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2016-08-27T00:45:07.412Z,1472258707.412 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2016-08-27T00:45:07.418Z,1472258707.418 [Science:PeakDetectNO3] Stopped 2016-08-27T00:45:07.418Z,1472258707.418 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3 2016-08-27T00:45:07.418Z,1472258707.418 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2016-08-27T00:45:07.418Z,1472258707.418 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2016-08-27T00:45:07.418Z,1472258707.418 [Science:PeakDetectChl] Stopped 2016-08-27T00:45:07.419Z,1472258707.419 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl 2016-08-27T00:45:07.419Z,1472258707.419 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2016-08-27T00:45:07.419Z,1472258707.419 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2016-08-27T00:45:07.419Z,1472258707.419 [Science:F] Running Loop=1 2016-08-27T00:45:07.420Z,1472258707.420 [Science:F](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2016-08-27T00:45:07.421Z,1472258707.421 [Science:E] Stopped 2016-08-27T00:45:07.421Z,1472258707.421 [Science:D] Stopped 2016-08-27T00:45:07.421Z,1472258707.421 [Science:C] Running Loop=1 2016-08-27T00:45:07.422Z,1472258707.422 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2016-08-27T00:45:07.422Z,1472258707.422 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2016-08-27T00:45:07.423Z,1472258707.423 [Science:B] Stopped 2016-08-27T00:45:07.423Z,1472258707.423 [Science:A] Running Loop=1 2016-08-27T00:45:07.424Z,1472258707.424 [Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water 2016-08-27T00:45:07.468Z,1472258707.468 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2016-08-27T00:45:07.645Z,1472258707.645 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4 2016-08-27T00:45:07.645Z,1472258707.645 [WetLabsBB2FL](INFO): Powering up 2016-08-27T00:45:07.889Z,1472258707.889 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:45:08.295Z,1472258708.295 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:45:08.707Z,1472258708.707 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:45:08.961Z,1472258708.961 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.007300 psu 2016-08-27T00:45:08.963Z,1472258708.963 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:09.156Z,1472258709.156 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.011800 psu 2016-08-27T00:45:09.158Z,1472258709.158 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:09.358Z,1472258709.358 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.047900 psu 2016-08-27T00:45:09.360Z,1472258709.360 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:09.449Z,1472258709.449 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:45:09.562Z,1472258709.562 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.026100 psu 2016-08-27T00:45:09.563Z,1472258709.563 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:09.774Z,1472258709.774 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.002200 psu 2016-08-27T00:45:09.775Z,1472258709.775 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:09.978Z,1472258709.978 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.026000 psu 2016-08-27T00:45:09.979Z,1472258709.979 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:10.175Z,1472258710.175 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.034000 psu 2016-08-27T00:45:10.176Z,1472258710.176 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:10.379Z,1472258710.379 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.059000 psu 2016-08-27T00:45:10.380Z,1472258710.380 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:10.587Z,1472258710.587 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.039600 psu 2016-08-27T00:45:10.589Z,1472258710.589 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:10.780Z,1472258710.780 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045700 psu 2016-08-27T00:45:10.781Z,1472258710.781 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:10.991Z,1472258710.991 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.055200 psu 2016-08-27T00:45:10.993Z,1472258710.993 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:11.292Z,1472258711.292 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.064400 psu 2016-08-27T00:45:11.293Z,1472258711.293 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:11.493Z,1472258711.493 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.060900 psu 2016-08-27T00:45:11.495Z,1472258711.495 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:11.698Z,1472258711.698 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072100 psu 2016-08-27T00:45:11.699Z,1472258711.699 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:11.902Z,1472258711.902 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.060300 psu 2016-08-27T00:45:11.903Z,1472258711.903 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:12.103Z,1472258712.103 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.064800 psu 2016-08-27T00:45:12.104Z,1472258712.104 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:12.306Z,1472258712.306 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.051100 psu 2016-08-27T00:45:12.308Z,1472258712.308 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:12.511Z,1472258712.511 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082500 psu 2016-08-27T00:45:12.512Z,1472258712.512 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:12.727Z,1472258712.727 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071000 psu 2016-08-27T00:45:12.729Z,1472258712.729 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:12.916Z,1472258712.916 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.069500 psu 2016-08-27T00:45:12.917Z,1472258712.917 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:13.128Z,1472258713.128 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.051800 psu 2016-08-27T00:45:13.129Z,1472258713.129 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:13.332Z,1472258713.332 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.050600 psu 2016-08-27T00:45:13.334Z,1472258713.334 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:13.524Z,1472258713.524 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.065300 psu 2016-08-27T00:45:13.526Z,1472258713.526 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:13.726Z,1472258713.726 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.052600 psu 2016-08-27T00:45:13.730Z,1472258713.730 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:13.929Z,1472258713.929 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.007500 psu 2016-08-27T00:45:13.930Z,1472258713.930 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:14.140Z,1472258714.140 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.047300 psu 2016-08-27T00:45:14.141Z,1472258714.141 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:14.342Z,1472258714.342 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.029800 psu 2016-08-27T00:45:14.343Z,1472258714.343 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:14.553Z,1472258714.553 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.037000 psu 2016-08-27T00:45:14.555Z,1472258714.555 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:14.747Z,1472258714.747 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.046200 psu 2016-08-27T00:45:14.754Z,1472258714.754 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:14.962Z,1472258714.962 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.035500 psu 2016-08-27T00:45:14.963Z,1472258714.963 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:15.147Z,1472258715.147 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.062000 psu 2016-08-27T00:45:15.149Z,1472258715.149 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:15.354Z,1472258715.354 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.046900 psu 2016-08-27T00:45:15.356Z,1472258715.356 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:15.395Z,1472258715.395 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 226.367 83.327 22.249 30.043 30.043 38.999 8.956 623.9 882.5 23.9 2016-08-27T00:45:15.395Z,1472258715.395 [Aanderaa_O2](DEBUG): Error parsing device response 2016-08-27T00:45:15.559Z,1472258715.559 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.067100 psu 2016-08-27T00:45:15.560Z,1472258715.560 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:15.761Z,1472258715.761 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.057900 psu 2016-08-27T00:45:15.763Z,1472258715.763 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:15.964Z,1472258715.964 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045100 psu 2016-08-27T00:45:15.965Z,1472258715.965 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:16.181Z,1472258716.181 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.031500 psu 2016-08-27T00:45:16.182Z,1472258716.182 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:16.368Z,1472258716.368 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.054600 psu 2016-08-27T00:45:16.370Z,1472258716.370 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:16.573Z,1472258716.573 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.032800 psu 2016-08-27T00:45:16.574Z,1472258716.574 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:16.662Z,1472258716.662 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 226.445  83.354 22.248 30.040 30.040 38.993 8.953 623.8 881.3 24.0 2016-08-27T00:45:16.662Z,1472258716.662 [Aanderaa_O2](DEBUG): Error parsing device response 2016-08-27T00:45:16.785Z,1472258716.785 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.055700 psu 2016-08-27T00:45:16.786Z,1472258716.786 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:16.977Z,1472258716.977 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.056000 psu 2016-08-27T00:45:16.978Z,1472258716.978 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:17.182Z,1472258717.182 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.062200 psu 2016-08-27T00:45:17.183Z,1472258717.183 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:17.394Z,1472258717.394 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072500 psu 2016-08-27T00:45:17.395Z,1472258717.395 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:17.472Z,1472258717.472 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 226.336 83.314 22.248 30.045 30.045 38.995 8.950 623.7 880.4 24.0 2016-08-27T00:45:17.472Z,1472258717.472 [Aanderaa_O2](DEBUG): Error parsing device response 2016-08-27T00:45:17.586Z,1472258717.586 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.064100 psu 2016-08-27T00:45:17.588Z,1472258717.588 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:17.795Z,1472258717.795 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.053500 psu 2016-08-27T00:45:17.796Z,1472258717.796 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:18.099Z,1472258718.099 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.011500 psu 2016-08-27T00:45:18.100Z,1472258718.100 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:18.299Z,1472258718.299 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.149000 psu 2016-08-27T00:45:18.300Z,1472258718.300 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:18.499Z,1472258718.499 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.020800 psu 2016-08-27T00:45:18.501Z,1472258718.501 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:18.697Z,1472258718.697 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.039400 psu 2016-08-27T00:45:18.698Z,1472258718.698 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:18.766Z,1472258718.766 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:45:18.909Z,1472258718.908 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.036800 psu 2016-08-27T00:45:18.910Z,1472258718.910 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:19.113Z,1472258719.113 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.049300 psu 2016-08-27T00:45:19.114Z,1472258719.114 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:19.317Z,1472258719.317 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.033300 psu 2016-08-27T00:45:19.318Z,1472258719.318 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:19.513Z,1472258719.513 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.043700 psu 2016-08-27T00:45:19.515Z,1472258719.515 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:19.538Z,1472258719.538 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 226.357 83.323 22.249 30.044 30.044 38.995 8.951 623.5 878.9 23.9 2016-08-27T00:45:19.538Z,1472258719.538 [Aanderaa_O2](DEBUG): Error parsing device response 2016-08-27T00:45:19.718Z,1472258719.718 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045100 psu 2016-08-27T00:45:19.719Z,1472258719.719 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:19.926Z,1472258719.926 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.038300 psu 2016-08-27T00:45:19.927Z,1472258719.927 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:20.122Z,1472258720.122 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045400 psu 2016-08-27T00:45:20.123Z,1472258720.123 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:20.330Z,1472258720.330 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.044600 psu 2016-08-27T00:45:20.332Z,1472258720.332 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:20.405Z,1472258720.405 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:45:20.527Z,1472258720.527 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.058500 psu 2016-08-27T00:45:20.529Z,1472258720.529 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:20.735Z,1472258720.735 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.039000 psu 2016-08-27T00:45:20.737Z,1472258720.737 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:20.932Z,1472258720.932 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.046100 psu 2016-08-27T00:45:20.934Z,1472258720.934 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:21.136Z,1472258721.136 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.048400 psu 2016-08-27T00:45:21.139Z,1472258721.139 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:21.337Z,1472258721.337 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.026900 psu 2016-08-27T00:45:21.340Z,1472258721.340 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:21.403Z,1472258721.403 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:45:21.549Z,1472258721.549 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.013500 psu 2016-08-27T00:45:21.550Z,1472258721.550 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:21.750Z,1472258721.750 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.042600 psu 2016-08-27T00:45:21.751Z,1472258721.751 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:21.953Z,1472258721.953 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.056700 psu 2016-08-27T00:45:21.955Z,1472258721.955 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:22.154Z,1472258722.154 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.052500 psu 2016-08-27T00:45:22.155Z,1472258722.155 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:22.359Z,1472258722.359 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.046100 psu 2016-08-27T00:45:22.362Z,1472258722.362 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:22.558Z,1472258722.558 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.007800 psu 2016-08-27T00:45:22.560Z,1472258722.560 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:22.766Z,1472258722.766 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.067800 psu 2016-08-27T00:45:22.768Z,1472258722.768 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:22.967Z,1472258722.967 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045300 psu 2016-08-27T00:45:22.968Z,1472258722.968 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:23.175Z,1472258723.175 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.062800 psu 2016-08-27T00:45:23.176Z,1472258723.176 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:23.375Z,1472258723.375 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.041700 psu 2016-08-27T00:45:23.376Z,1472258723.376 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:23.579Z,1472258723.579 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.040400 psu 2016-08-27T00:45:23.580Z,1472258723.580 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:23.780Z,1472258723.780 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.037400 psu 2016-08-27T00:45:23.781Z,1472258723.781 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:23.980Z,1472258723.980 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.051600 psu 2016-08-27T00:45:23.982Z,1472258723.982 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:24.192Z,1472258724.192 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.051500 psu 2016-08-27T00:45:24.194Z,1472258724.194 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:24.209Z,1472258724.209 [Reporter](INFO): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.295968 ug/l 2016-08-27T00:45:24.389Z,1472258724.389 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.035300 psu 2016-08-27T00:45:24.390Z,1472258724.390 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:24.693Z,1472258724.693 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.059300 psu 2016-08-27T00:45:24.695Z,1472258724.695 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:24.898Z,1472258724.898 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078500 psu 2016-08-27T00:45:24.899Z,1472258724.899 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:25.098Z,1472258725.098 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.063400 psu 2016-08-27T00:45:25.100Z,1472258725.100 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:25.306Z,1472258725.306 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.030100 psu 2016-08-27T00:45:25.308Z,1472258725.308 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:25.502Z,1472258725.502 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.055000 psu 2016-08-27T00:45:25.504Z,1472258725.504 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:25.711Z,1472258725.711 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.051100 psu 2016-08-27T00:45:25.712Z,1472258725.712 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:25.907Z,1472258725.907 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.059700 psu 2016-08-27T00:45:25.909Z,1472258725.909 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:26.111Z,1472258726.111 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.052000 psu 2016-08-27T00:45:26.113Z,1472258726.113 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:26.324Z,1472258726.324 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.049000 psu 2016-08-27T00:45:26.326Z,1472258726.326 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:26.524Z,1472258726.524 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.052700 psu 2016-08-27T00:45:26.525Z,1472258726.525 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:26.724Z,1472258726.724 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.048100 psu 2016-08-27T00:45:26.726Z,1472258726.726 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:26.929Z,1472258726.929 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.056300 psu 2016-08-27T00:45:26.930Z,1472258726.930 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:27.133Z,1472258727.133 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045200 psu 2016-08-27T00:45:27.134Z,1472258727.134 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:27.338Z,1472258727.338 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.035700 psu 2016-08-27T00:45:27.340Z,1472258727.340 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:27.565Z,1472258727.565 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.032900 psu 2016-08-27T00:45:27.567Z,1472258727.567 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:27.734Z,1472258727.734 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.015100 psu 2016-08-27T00:45:27.735Z,1472258727.735 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:27.814Z,1472258727.814 [CTD_NeilBrown](DEBUG): serial timeout 2016-08-27T00:45:27.939Z,1472258727.939 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.023600 psu 2016-08-27T00:45:27.940Z,1472258727.940 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:28.144Z,1472258728.144 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.034700 psu 2016-08-27T00:45:28.155Z,1472258728.155 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:28.351Z,1472258728.351 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.028900 psu 2016-08-27T00:45:28.353Z,1472258728.353 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:28.548Z,1472258728.548 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.043900 psu 2016-08-27T00:45:28.549Z,1472258728.549 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:28.623Z,1472258728.623 [CommandLine](IMPORTANT): got command stop 2016-08-27T00:45:28.752Z,1472258728.752 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.043200 psu 2016-08-27T00:45:28.753Z,1472258728.753 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:28.929Z,1472258728.929 [Science] Stopped 2016-08-27T00:45:28.929Z,1472258728.929 [Science](DEBUG): Aggregate::uninitialize Science 2016-08-27T00:45:28.930Z,1472258728.930 [Science:A] Stopped 2016-08-27T00:45:28.930Z,1472258728.930 [Science:C] Stopped 2016-08-27T00:45:28.930Z,1472258728.930 [Science:F] Stopped 2016-08-27T00:45:28.964Z,1472258728.964 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.038900 psu 2016-08-27T00:45:28.965Z,1472258728.965 [CTD_NeilBrown](INFO): some bad data, not updating bins 2016-08-27T00:45:29.084Z,1472258729.084 [WetLabsBB2FL](INFO): Powering down 2016-08-27T00:45:29.284Z,1472258729.284 [MissionManager](IMPORTANT): Started mission Default 2016-08-27T00:45:29.284Z,1472258729.284 [Default] Running Loop=1 2016-08-27T00:45:29.284Z,1472258729.284 [Default](DEBUG): Aggregate::initialize Default 2016-08-27T00:45:29.285Z,1472258729.285 [Default:B.GoToSurface] Running Loop=1 2016-08-27T00:45:29.285Z,1472258729.285 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2016-08-27T00:45:29.292Z,1472258729.292 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2016-08-27T00:45:29.292Z,1472258729.292 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2016-08-27T00:45:29.293Z,1472258729.293 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2016-08-27T00:45:29.293Z,1472258729.293 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2016-08-27T00:45:29.294Z,1472258729.294 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2016-08-27T00:45:29.295Z,1472258729.295 [Default:A.Wait] Running Loop=1 2016-08-27T00:45:29.295Z,1472258729.295 [Default:A.Wait](DEBUG): Initialize Wait Component. 2016-08-27T00:45:29.669Z,1472258729.669 [Aanderaa_O2](INFO): Powering down 2016-08-27T00:45:42.593Z,1472258742.593 [Default:A.Wait](INFO): Done Waiting. 2016-08-27T00:45:42.593Z,1472258742.593 [Default:A.Wait] Stopped 2016-08-27T00:45:42.593Z,1472258742.593 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2016-08-27T00:45:42.984Z,1472258742.984 [Default:CheckIn] Running Loop=1 2016-08-27T00:45:42.984Z,1472258742.984 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2016-08-27T00:45:42.984Z,1472258742.984 [Default:CheckIn:Read_GPS] Running Loop=1 2016-08-27T00:46:49.703Z,1472258809.703 [CommandLine](IMPORTANT): got command show variable photosyn 2016-08-27T00:46:49.705Z,1472258809.705 [CommandLine](IMPORTANT): downwelling_photosynthetic_photon_flux_in_sea_water (unknown) 2016-08-27T00:46:49.873Z,1472258809.873 [CommandLine](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water (micromole_per_second_per_square_meter) 2016-08-27T00:46:52.948Z,1472258812.948 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:46:52.949Z,1472258812.949 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2016-08-27T00:46:52.949Z,1472258812.949 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2016-08-27T00:46:52.951Z,1472258812.951 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-27T00:46:52.951Z,1472258812.951 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2016-08-27T00:46:52.951Z,1472258812.951 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2016-08-27T00:46:52.989Z,1472258812.989 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2016-08-27T00:46:52.990Z,1472258812.990 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2016-08-27T00:46:53.394Z,1472258813.394 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2016-08-27T00:46:53.394Z,1472258813.394 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2016-08-27T00:46:53.395Z,1472258813.395 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2016-08-27T00:46:53.395Z,1472258813.395 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2016-08-27T00:46:53.767Z,1472258813.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-08-27T00:46:53.767Z,1472258813.767 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:46:53.768Z,1472258813.768 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:46:53.768Z,1472258813.768 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-08-27T00:46:53.769Z,1472258813.769 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-08-27T00:46:53.769Z,1472258813.769 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-08-27T00:46:57.188Z,1472258817.188 [CommandLine](IMPORTANT): got command get PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2016-08-27T00:46:57.188Z,1472258817.188 [CommandLine](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 13.218697 umol/s/m2 2016-08-27T00:47:03.251Z,1472258823.251 [CommandLine](IMPORTANT): got command get PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2016-08-27T00:47:03.252Z,1472258823.252 [CommandLine](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 227.559256 umol/s/m2 2016-08-27T00:47:08.900Z,1472258828.900 [CommandLine](IMPORTANT): got command get PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2016-08-27T00:47:08.901Z,1472258828.901 [CommandLine](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.540291 umol/s/m2 2016-08-27T00:48:12.235Z,1472258892.235 [BPC1](ERROR): Failed to receive battery data 2016-08-27T00:48:12.235Z,1472258892.235 [BPC1] Communications Fault, FailCount= 1 2016-08-27T00:48:12.235Z,1472258892.235 [BPC1](ERROR): Communications Fault 2016-08-27T00:48:12.258Z,1472258892.258 [CBIT](ERROR): Communications Fault in component: BPC1 2016-08-27T00:48:13.454Z,1472258893.454 [CBIT](INFO): Clearing failed state for component BPC1 2016-08-27T00:48:13.454Z,1472258893.454 [BPC1] No Fault, FailCount= 1 2016-08-27T00:48:29.457Z,1472258909.457 [BPC1](ERROR): Failed to receive battery data 2016-08-27T00:48:29.457Z,1472258909.457 [BPC1] Communications Fault, FailCount= 2 2016-08-27T00:48:29.458Z,1472258909.458 [BPC1](ERROR): Communications Fault 2016-08-27T00:48:29.569Z,1472258909.569 [CBIT](ERROR): Communications Fault in component: BPC1 2016-08-27T00:48:30.205Z,1472258910.205 [PNI_TCM](DEBUG): LCB error: Software Overcurrent. 2016-08-27T00:48:30.609Z,1472258910.609 [PNI_TCM](DEBUG): LCB error: Software Overcurrent. 2016-08-27T00:48:30.661Z,1472258910.661 [CBIT](INFO): Clearing failed state for component BPC1 2016-08-27T00:48:30.662Z,1472258910.662 [BPC1] No Fault, FailCount= 2 2016-08-27T00:48:34.319Z,1472258914.319 [CommandLine](IMPORTANT): got command restart system 2016-08-27T00:48:35.760Z,1472258915.760 [PNI_TCM](DEBUG): LCB error: Software Overcurrent. 2016-08-27T00:48:36.027Z,1472258916.027 [PNI_TCM](DEBUG): LCB error: Software Overcurrent. 2016-08-27T00:48:36.434Z,1472258916.434 [PNI_TCM](DEBUG): LCB error: Software Overcurrent. 2016-08-27T00:48:36.786Z,1472258916.786 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2016-08-27T00:48:36.787Z,1472258916.787 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:36.798Z,1472258916.798 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2016-08-27T00:48:36.798Z,1472258916.798 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:36.799Z,1472258916.799 [CommandLine](INFO): Join timeout helper Thread ID is 1113 2016-08-27T00:48:36.809Z,1472258916.809 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2016-08-27T00:48:36.809Z,1472258916.809 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:36.810Z,1472258916.810 [NavChartDb](INFO): Join timeout helper Thread ID is 1114 2016-08-27T00:48:36.854Z,1472258916.854 [PNI_TCM](DEBUG): LCB error: Software Overcurrent. 2016-08-27T00:48:37.191Z,1472258917.191 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2016-08-27T00:48:37.191Z,1472258917.191 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.195Z,1472258917.195 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2016-08-27T00:48:37.195Z,1472258917.195 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.195Z,1472258917.195 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1116 2016-08-27T00:48:37.227Z,1472258917.227 [PNI_TCM](DEBUG): LCB error: Software Overcurrent. 2016-08-27T00:48:37.339Z,1472258917.339 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2016-08-27T00:48:37.340Z,1472258917.340 [Rowe_600LCM](INFO): Powering down 2016-08-27T00:48:37.341Z,1472258917.341 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.347Z,1472258917.347 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2016-08-27T00:48:37.347Z,1472258917.347 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.348Z,1472258917.348 [Radio_Surface](INFO): Join timeout helper Thread ID is 1117 2016-08-27T00:48:37.591Z,1472258917.591 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2016-08-27T00:48:37.592Z,1472258917.592 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.592Z,1472258917.592 [PNI_TCM](DEBUG): LCB error: Software Overcurrent. 2016-08-27T00:48:37.615Z,1472258917.615 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2016-08-27T00:48:37.615Z,1472258917.615 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.616Z,1472258917.616 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1118 2016-08-27T00:48:37.644Z,1472258917.644 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2016-08-27T00:48:37.644Z,1472258917.644 [WetLabsBB2FL](INFO): Powering down 2016-08-27T00:48:37.645Z,1472258917.645 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.656Z,1472258917.656 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2016-08-27T00:48:37.656Z,1472258917.656 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.657Z,1472258917.657 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1119 2016-08-27T00:48:37.740Z,1472258917.740 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2016-08-27T00:48:37.740Z,1472258917.740 [CTD_NeilBrown](INFO): Powering down 2016-08-27T00:48:37.741Z,1472258917.741 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.748Z,1472258917.748 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2016-08-27T00:48:37.749Z,1472258917.749 [logger ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.749Z,1472258917.749 [logger](INFO): Join timeout helper Thread ID is 1120 2016-08-27T00:48:37.760Z,1472258917.760 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2016-08-27T00:48:37.760Z,1472258917.760 [logger ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.768Z,1472258917.768 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2016-08-27T00:48:37.768Z,1472258917.768 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.768Z,1472258917.768 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2016-08-27T00:48:37.769Z,1472258917.769 [controlThread ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:37.769Z,1472258917.769 [controlThread](INFO): Join timeout helper Thread ID is 1121 2016-08-27T00:48:37.960Z,1472258917.960 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2016-08-27T00:48:37.960Z,1472258917.960 [controlThread](DEBUG): Uninitializing ControlThread 2016-08-27T00:48:37.961Z,1472258917.961 [Aanderaa_O2](INFO): Powering down 2016-08-27T00:48:37.962Z,1472258917.962 [AcousticModem_Benthos_ATM900](INFO): Powering down 2016-08-27T00:48:37.963Z,1472258917.963 [NAL9602](INFO): Powering down 2016-08-27T00:48:37.964Z,1472258917.964 [PNI_TCM](INFO): Powering down 2016-08-27T00:48:37.965Z,1472258917.965 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2016-08-27T00:48:37.967Z,1472258917.967 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2016-08-27T00:48:37.967Z,1472258917.967 [Default] Stopped 2016-08-27T00:48:37.967Z,1472258917.967 [Default](DEBUG): Aggregate::uninitialize Default 2016-08-27T00:48:37.967Z,1472258917.967 [Default:B.GoToSurface] Stopped 2016-08-27T00:48:37.967Z,1472258917.967 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2016-08-27T00:48:37.967Z,1472258917.967 [Default:CheckIn] Stopped 2016-08-27T00:48:37.967Z,1472258917.967 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2016-08-27T00:48:37.968Z,1472258917.968 [Default:CheckIn:Read_GPS] Stopped 2016-08-27T00:48:37.970Z,1472258917.970 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2016-08-27T00:48:37.970Z,1472258917.970 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2016-08-27T00:48:37.971Z,1472258917.971 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2016-08-27T00:48:37.971Z,1472258917.971 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2016-08-27T00:48:37.971Z,1472258917.971 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2016-08-27T00:48:37.971Z,1472258917.971 [BuoyancyServo](INFO): Powering down 2016-08-27T00:48:37.984Z,1472258917.984 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2016-08-27T00:48:37.984Z,1472258917.984 [ElevatorServo](INFO): Powering down 2016-08-27T00:48:37.985Z,1472258917.985 [MassServo](DEBUG): Uninitialize Mass Servo. 2016-08-27T00:48:37.985Z,1472258917.985 [MassServo](INFO): Powering down 2016-08-27T00:48:37.986Z,1472258917.986 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2016-08-27T00:48:37.986Z,1472258917.986 [RudderServo](INFO): Powering down 2016-08-27T00:48:37.987Z,1472258917.987 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2016-08-27T00:48:37.987Z,1472258917.987 [ThrusterServo](INFO): Powering down 2016-08-27T00:48:37.988Z,1472258917.988 [SBIT](DEBUG): Uninitialize SBIT Component. 2016-08-27T00:48:37.988Z,1472258917.988 [IBIT](DEBUG): Uninitialize IBIT Component. 2016-08-27T00:48:37.988Z,1472258917.988 [CBIT](DEBUG): Uninitialize CBIT Component. 2016-08-27T00:48:38.021Z,1472258918.021 [controlThread ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:38.080Z,1472258918.080 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:38.085Z,1472258918.085 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:38.146Z,1472258918.146 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:38.175Z,1472258918.175 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:38.220Z,1472258918.220 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-08-27T00:48:38.270Z,1472258918.270 [logger ThreadHandler](INFO): Thread cancelled.