2010/09/27 18:48:13 - currents1 2.6.32-24-generic #42-Ubuntu SMP Fri Aug 20 14:24:04 UTC 2010 i686 2010/09/27 18:48:14 - tk_terminal 2010/09/27 18:48:14 - 18:48:14 2010/09/27 18:48:14 - /dev/ttyn0 2010/09/27 18:48:14 - /dev/ttyn3 2010/09/27 18:48:14 - In CruiseSetup.init 2010/09/27 18:53:01 - adcptree.py /home/data/lucid_test01/proc/os75bb /home/currents/programs -d uhdas -i os -n os75 --configpath /home/data/lucid_test01/raw/config --configbase lucid_test01 -p bb 2010/09/27 18:53:01 - /home/currents/programs/uhdas/uhdas/cruisesetup.py:278: DeprecationWarning: os.popen4 is deprecated. Use the subprocess module. (inpipe, outpipe) = os.popen4(cmd) 2010/09/27 18:53:01 - uh_progs = /home/currents/programs otherdemo is /home/currents/programs/adcp_templates/uhdas_template making directory /home/data/lucid_test01/proc/os75bb/adcpdb making directory /home/data/lucid_test01/proc/os75bb/cal making directory /home/data/lucid_test01/proc/os75bb/cal/watertrk making directory /home/data/lucid_test01/proc/os75bb/cal/botmtrk making directory /home/data/lucid_test01/proc/os75bb/cal/heading making directory /home/data/lucid_test01/proc/os75bb/cal/rotate making directory /home/data/lucid_test01/proc/os75bb/contour making directory /home/data/lucid_test01/proc/os75bb/edit making directory /home/data/lucid_test01/proc/os75bb/grid making directory /home/data/lucid_test01/proc/os75bb/load making directory /home/data/lucid_test01/proc/os75bb/nav making directory /home/data/lucid_test01/proc/os75bb/quality making directory /home/data/lucid_test01/proc/os75bb/ping making directory /home/data/lucid_test01/proc/os75bb/scan making directory /home/data/lucid_test01/proc/os75bb/stick making directory /home/data/lucid_test01/proc/os75bb/vector ** data type is uhdas copying additional files for data type uhdas file exists: not copying vmadcp.def to /home/data/lucid_test01/proc/os75bb/load configbase lucid_test01 configpath /home/data/lucid_test01/raw/config datatype uhdas demopath /home/currents/programs/adcp_templates/demo docpath /home/currents/programs/adcp_doc help 0 instclass nb pingtype nb procdir /home/data/lucid_test01/proc/os75bb uh_progs /home/currents/programs varvals 0 verbose 0 config files for raw processing are in /home/data/lucid_test01/proc/os75bb/config ** UHDAS demo data are in /home/currents/programs/q_demos/uhdas/data (1) writing template text file for processing notes: /home/data/lucid_test01/proc/os75bb/template_rpt.txt (2) creating local web page for documentation open this file in your web browser: /home/data/lucid_test01/proc/os75bb/adcp_processing.html done. 2010/09/27 18:53:01 - adcptree.py /home/data/lucid_test01/proc/os75nb /home/currents/programs -d uhdas -i os -n os75 --configpath /home/data/lucid_test01/raw/config --configbase lucid_test01 -p nb 2010/09/27 18:53:01 - uh_progs = /home/currents/programs otherdemo is /home/currents/programs/adcp_templates/uhdas_template making directory /home/data/lucid_test01/proc/os75nb/adcpdb making directory /home/data/lucid_test01/proc/os75nb/cal making directory /home/data/lucid_test01/proc/os75nb/cal/watertrk making directory /home/data/lucid_test01/proc/os75nb/cal/botmtrk making directory /home/data/lucid_test01/proc/os75nb/cal/heading making directory /home/data/lucid_test01/proc/os75nb/cal/rotate making directory /home/data/lucid_test01/proc/os75nb/contour making directory /home/data/lucid_test01/proc/os75nb/edit making directory /home/data/lucid_test01/proc/os75nb/grid making directory /home/data/lucid_test01/proc/os75nb/load making directory /home/data/lucid_test01/proc/os75nb/nav making directory /home/data/lucid_test01/proc/os75nb/quality making directory /home/data/lucid_test01/proc/os75nb/ping making directory /home/data/lucid_test01/proc/os75nb/scan making directory /home/data/lucid_test01/proc/os75nb/stick making directory /home/data/lucid_test01/proc/os75nb/vector ** data type is uhdas copying additional files for data type uhdas file exists: not copying vmadcp.def to /home/data/lucid_test01/proc/os75nb/load configbase lucid_test01 configpath /home/data/lucid_test01/raw/config datatype uhdas demopath /home/currents/programs/adcp_templates/demo docpath /home/currents/programs/adcp_doc help 0 instclass nb pingtype nb procdir /home/data/lucid_test01/proc/os75nb uh_progs /home/currents/programs varvals 0 verbose 0 config files for raw processing are in /home/data/lucid_test01/proc/os75nb/config ** UHDAS demo data are in /home/currents/programs/q_demos/uhdas/data (1) writing template text file for processing notes: /home/data/lucid_test01/proc/os75nb/template_rpt.txt (2) creating local web page for documentation open this file in your web browser: /home/data/lucid_test01/proc/os75nb/adcp_processing.html done. 2010/09/27 18:53:01 - adcptree.py /home/data/lucid_test01/proc/nb150 /home/currents/programs -d uhdas -i nb -n nb150 --configpath /home/data/lucid_test01/raw/config --configbase lucid_test01 2010/09/27 18:53:01 - uh_progs = /home/currents/programs otherdemo is /home/currents/programs/adcp_templates/uhdas_template making directory /home/data/lucid_test01/proc/nb150/adcpdb making directory /home/data/lucid_test01/proc/nb150/cal making directory /home/data/lucid_test01/proc/nb150/cal/watertrk making directory /home/data/lucid_test01/proc/nb150/cal/botmtrk making directory /home/data/lucid_test01/proc/nb150/cal/heading making directory /home/data/lucid_test01/proc/nb150/cal/rotate making directory /home/data/lucid_test01/proc/nb150/contour making directory /home/data/lucid_test01/proc/nb150/edit making directory /home/data/lucid_test01/proc/nb150/grid making directory /home/data/lucid_test01/proc/nb150/load making directory /home/data/lucid_test01/proc/nb150/nav making directory /home/data/lucid_test01/proc/nb150/quality making directory /home/data/lucid_test01/proc/nb150/ping making directory /home/data/lucid_test01/proc/nb150/scan making directory /home/data/lucid_test01/proc/nb150/stick making directory /home/data/lucid_test01/proc/nb150/vector ** data type is uhdas copying additional files for data type uhdas file exists: not copying vmadcp.def to /home/data/lucid_test01/proc/nb150/load configbase lucid_test01 configpath /home/data/lucid_test01/raw/config datatype uhdas demopath /home/currents/programs/adcp_templates/demo docpath /home/currents/programs/adcp_doc help 0 instclass nb pingtype nb procdir /home/data/lucid_test01/proc/nb150 uh_progs /home/currents/programs varvals 0 verbose 0 config files for raw processing are in /home/data/lucid_test01/proc/nb150/config ** UHDAS demo data are in /home/currents/programs/q_demos/uhdas/data (1) writing template text file for processing notes: /home/data/lucid_test01/proc/nb150/template_rpt.txt (2) creating local web page for documentation open this file in your web browser: /home/data/lucid_test01/proc/nb150/adcp_processing.html done. 2010/09/27 18:53:02 - Entering buildLoggers 2010/09/27 18:53:02 - Created output directory with mode 775 2010/09/27 18:53:02 - Created output directory with mode 775 2010/09/27 18:53:02 - Created output directory with mode 775 2010/09/27 18:53:02 - Created output directory with mode 775 2010/09/27 18:53:02 - Created output directory with mode 775 2010/09/27 18:53:02 - >>>> C_ss enabled <<<<< 2010/09/27 18:53:02 - Leaving buildLoggers 2010/09/27 18:53:02 - Start Cruise 2010/09/27 18:53:08 - enter StartLogging, resume = 2010/09/27 18:53:08 - 0 2010/09/27 18:53:12 - TS? 2010/09/27 18:53:12 - TS100927185312 2010/09/27 18:53:13 - CR1 2010/09/27 18:53:13 - WD 111 00 0000 2010/09/27 18:53:13 - ND 111 00 0000 2010/09/27 18:53:13 - EA04500 2010/09/27 18:53:13 - NP1 2010/09/27 18:53:13 - NN70 2010/09/27 18:53:13 - NS1600 2010/09/27 18:53:13 - NF800 2010/09/27 18:53:13 - WP1 2010/09/27 18:53:13 - WN80 2010/09/27 18:53:13 - WS800 2010/09/27 18:53:13 - WF800 2010/09/27 18:53:13 - BP0 2010/09/27 18:53:13 - BX10000 2010/09/27 18:53:13 - TP00:01.80 2010/09/27 18:53:13 - CX0,0 2010/09/27 18:53:13 - B? 2010/09/27 18:53:14 - N? 2010/09/27 18:53:15 - W? 2010/09/27 18:53:15 - E? 2010/09/27 18:53:17 - C? 2010/09/27 18:53:18 - T? 2010/09/27 18:53:18 - PS0 2010/09/27 18:53:18 - In os_check_D_params 2010/09/27 18:53:18 - in os_check_D_params: 111000000 2010/09/27 18:53:18 - in os_check_D_params: 111000000 2010/09/27 18:53:18 - CF11110 2010/09/27 18:53:26 - term 1 ADCP nb150 startup failure 2010/09/27 18:53:26 - Traceback (most recent call last): 2010/09/27 18:53:26 - File "/home/currents/programs/uhdas/uhdas/DAS.py", line 281, in StartLogging 2010/09/27 18:53:26 - term.start_binary(cmdlist) 2010/09/27 18:53:26 - File "/home/currents/programs/uhdas/serial/nb_term.py", line 161, in start_binary 2010/09/27 18:53:26 - self.wakeup() 2010/09/27 18:53:26 - File "/home/currents/programs/uhdas/serial/nb_term.py", line 72, in wakeup 2010/09/27 18:53:26 - self.waitfor(_CRLF, timeout=1, quiet=0.5) 2010/09/27 18:53:26 - File "/home/currents/programs/uhdas/serial/tk_terminal.py", line 288, in waitfor 2010/09/27 18:53:26 - raise Timeout 2010/09/27 18:53:26 - Timeout 2010/09/27 18:53:50 - Entering Loggers.start_logging 2010/09/27 18:53:50 - /usr/local/bin/ser_bin -y 2010 -P ttyn0 -b 19200 -d /home/data/lucid_test01/raw/os75 -i /tmp/SerialLogger/inpipe.ttyn0 -o /tmp/SerialLogger/outpipe.ttyn0 -T 1285613630 -f oc -F -m 1 -H 2 -e raw -rlE -c -O -I 2> /tmp/stderr.ttyn0 & 2010/09/27 18:53:50 - /usr/local/bin/ser_bin -y 2010 -P ttyn3 -b 19200 -d /home/data/lucid_test01/raw/nb150 -i /tmp/SerialLogger/inpipe.ttyn3 -o /tmp/SerialLogger/outpipe.ttyn3 -T 1285613630 -f oc -F -m 1 -H 2 -e raw -rlE -c -I 2> /tmp/stderr.ttyn3 & 2010/09/27 18:53:51 - /usr/local/bin/ser_asc -y 2010 -P ttyn2 -b 9600 -d /home/data/lucid_test01/raw/ashtech -i /tmp/SerialLogger/inpipe.ttyn2 -o /tmp/SerialLogger/outpipe.ttyn2 -T 1285613630 -f oc -F -m 1 -H 2 -e adu -tc '$GPGGA' '$PASHR,ATT' 2> /tmp/stderr.ttyn2 & 2010/09/27 18:53:51 - /usr/local/bin/ser_asc -y 2010 -P ttyn4 -b 4800 -d /home/data/lucid_test01/raw/gpsnav -i /tmp/SerialLogger/inpipe.ttyn4 -o /tmp/SerialLogger/outpipe.ttyn4 -T 1285613630 -f oc -F -m 1 -H 2 -e gps -tc '$GPGGA' 2> /tmp/stderr.ttyn4 & 2010/09/27 18:53:51 - /usr/local/bin/ser_asc -y 2010 -P ttyn1 -b 9600 -d /home/data/lucid_test01/raw/gyro -i /tmp/SerialLogger/inpipe.ttyn1 -o /tmp/SerialLogger/outpipe.ttyn1 -T 1285613630 -f oc -F -m 1 -H 2 -e hdg -tc '$HEHDT' 2> /tmp/stderr.ttyn1 & 2010/09/27 18:53:51 - Leaving Loggers.start_logging 2010/09/27 18:53:51 - Updated SVs_present from SVs and wrote cmds 2010/09/27 18:53:51 - 0 2010/09/27 18:53:51 - Updated SVs_present from SVs and wrote cmds 2010/09/27 18:53:51 - 1 2010/09/27 18:53:51 - Running /home/currents/programs/uhdas/scripts/DAS_while_logging.py 2010/09/27 18:53:51 - Exiting StartLogging 2010/09/27 18:56:24 - Entering StopLogging 2010/09/27 18:56:26 - Loggers.check_stop_logging: all log processes ended 2010/09/27 18:56:31 - TS? 2010/09/27 18:56:38 - Wakeup of nb150 timed out. 2010/09/27 18:56:38 - Removed /home/adcp/flags/DAS.logging 2010/09/27 18:56:38 - Exiting StopLogging 2010/09/27 18:56:44 - enter StartLogging, resume = 2010/09/27 18:56:44 - 0 2010/09/27 18:56:49 - TS? 2010/09/27 18:56:50 - TS100927185650 2010/09/27 18:56:50 - CR1 2010/09/27 18:56:50 - WD 111 00 0000 2010/09/27 18:56:50 - ND 111 00 0000 2010/09/27 18:56:50 - EA04500 2010/09/27 18:56:50 - NP1 2010/09/27 18:56:50 - NN70 2010/09/27 18:56:50 - NS1600 2010/09/27 18:56:50 - NF800 2010/09/27 18:56:50 - WP1 2010/09/27 18:56:50 - WN80 2010/09/27 18:56:51 - WS800 2010/09/27 18:56:51 - WF800 2010/09/27 18:56:51 - BP0 2010/09/27 18:56:51 - BX10000 2010/09/27 18:56:51 - TP00:01.80 2010/09/27 18:56:51 - CX0,0 2010/09/27 18:56:51 - B? 2010/09/27 18:56:52 - N? 2010/09/27 18:56:52 - W? 2010/09/27 18:56:53 - E? 2010/09/27 18:56:54 - C? 2010/09/27 18:56:55 - T? 2010/09/27 18:56:56 - PS0 2010/09/27 18:56:56 - In os_check_D_params 2010/09/27 18:56:56 - in os_check_D_params: 111000000 2010/09/27 18:56:56 - in os_check_D_params: 111000000 2010/09/27 18:56:56 - CF11110 2010/09/27 18:57:04 - term 1 ADCP nb150 startup failure 2010/09/27 18:57:04 - Traceback (most recent call last): 2010/09/27 18:57:04 - File "/home/currents/programs/uhdas/uhdas/DAS.py", line 281, in StartLogging 2010/09/27 18:57:04 - term.start_binary(cmdlist) 2010/09/27 18:57:04 - File "/home/currents/programs/uhdas/serial/nb_term.py", line 161, in start_binary 2010/09/27 18:57:04 - self.wakeup() 2010/09/27 18:57:04 - File "/home/currents/programs/uhdas/serial/nb_term.py", line 72, in wakeup 2010/09/27 18:57:04 - self.waitfor(_CRLF, timeout=1, quiet=0.5) 2010/09/27 18:57:04 - File "/home/currents/programs/uhdas/serial/tk_terminal.py", line 288, in waitfor 2010/09/27 18:57:04 - raise Timeout 2010/09/27 18:57:04 - Timeout 2010/09/27 18:57:24 - TS? 2010/09/27 18:57:31 - Exception in Tk callback Function: > (type: ) Args: () Traceback (innermost last): File "/usr/lib/python2.6/dist-packages/Pmw/Pmw_1_3/lib/PmwBase.py", line 1747, in __call__ return apply(self.func, args) File "/home/currents/programs/pycurrents/system/startstop.py", line 67, in C_start self.funcs[0]() File "/home/currents/programs/uhdas/uhdas/DAS.py", line 293, in StartLogging t.wakeup() File "/home/currents/programs/uhdas/serial/nb_term.py", line 72, in wakeup self.waitfor(_CRLF, timeout=1, quiet=0.5) File "/home/currents/programs/uhdas/serial/tk_terminal.py", line 288, in waitfor raise Timeout : 2010/09/27 18:58:46 - Stopping DAS_while_cruise.py