2017/09/15 15:52:56 - currents1665 3.13.0-24-generic #47-Ubuntu SMP Fri May 2 23:30:00 UTC 2014 x86_64 2017/09/15 15:52:56 - /dev/ttyUSB4 2017/09/15 15:52:56 - /dev/ttyUSB5 2017/09/15 15:52:56 - In CruiseSetup.init 2017/09/15 15:52:56 - About to run mainloop 2017/09/15 15:53:15 - adcptree.py /home/data/EX1708_03/proc/wh300 -d uhdas --configpath /home/data/EX1708_03/raw/config --cruisename EX1708_03 2017/09/15 15:53:15 - found adcp templates at /usr/local/lib/python2.7/dist-packages/pycurrents/adcp/templates otherdemo is /usr/local/lib/python2.7/dist-packages/pycurrents/adcp/templates/uhdas_template ** data type is uhdas making directory /home/data/EX1708_03/proc/wh300/adcpdb making directory /home/data/EX1708_03/proc/wh300/cal making directory /home/data/EX1708_03/proc/wh300/cal/watertrk making directory /home/data/EX1708_03/proc/wh300/cal/botmtrk making directory /home/data/EX1708_03/proc/wh300/cal/heading making directory /home/data/EX1708_03/proc/wh300/cal/rotate making directory /home/data/EX1708_03/proc/wh300/contour making directory /home/data/EX1708_03/proc/wh300/edit making directory /home/data/EX1708_03/proc/wh300/grid making directory /home/data/EX1708_03/proc/wh300/load making directory /home/data/EX1708_03/proc/wh300/nav making directory /home/data/EX1708_03/proc/wh300/quality making directory /home/data/EX1708_03/proc/wh300/ping making directory /home/data/EX1708_03/proc/wh300/scan making directory /home/data/EX1708_03/proc/wh300/stick making directory /home/data/EX1708_03/proc/wh300/vector - copying additional files for data type uhdas - config files for raw processing are in /home/data/EX1708_03/proc/wh300/config copying config files using this wildcard expansion: /home/data/EX1708_03/raw/config/EX1708_03* 2017/09/15 15:53:15 - adcptree.py /home/data/EX1708_03/proc/os38bb -d uhdas --configpath /home/data/EX1708_03/raw/config --cruisename EX1708_03 2017/09/15 15:53:15 - found adcp templates at /usr/local/lib/python2.7/dist-packages/pycurrents/adcp/templates otherdemo is /usr/local/lib/python2.7/dist-packages/pycurrents/adcp/templates/uhdas_template ** data type is uhdas making directory /home/data/EX1708_03/proc/os38bb/adcpdb making directory /home/data/EX1708_03/proc/os38bb/cal making directory /home/data/EX1708_03/proc/os38bb/cal/watertrk making directory /home/data/EX1708_03/proc/os38bb/cal/botmtrk making directory /home/data/EX1708_03/proc/os38bb/cal/heading making directory /home/data/EX1708_03/proc/os38bb/cal/rotate making directory /home/data/EX1708_03/proc/os38bb/contour making directory /home/data/EX1708_03/proc/os38bb/edit making directory /home/data/EX1708_03/proc/os38bb/grid making directory /home/data/EX1708_03/proc/os38bb/load making directory /home/data/EX1708_03/proc/os38bb/nav making directory /home/data/EX1708_03/proc/os38bb/quality making directory /home/data/EX1708_03/proc/os38bb/ping making directory /home/data/EX1708_03/proc/os38bb/scan making directory /home/data/EX1708_03/proc/os38bb/stick making directory /home/data/EX1708_03/proc/os38bb/vector - copying additional files for data type uhdas - config files for raw processing are in /home/data/EX1708_03/proc/os38bb/config copying config files using this wildcard expansion: /home/data/EX1708_03/raw/config/EX1708_03* 2017/09/15 15:53:15 - adcptree.py /home/data/EX1708_03/proc/os38nb -d uhdas --configpath /home/data/EX1708_03/raw/config --cruisename EX1708_03 2017/09/15 15:53:15 - found adcp templates at /usr/local/lib/python2.7/dist-packages/pycurrents/adcp/templates otherdemo is /usr/local/lib/python2.7/dist-packages/pycurrents/adcp/templates/uhdas_template ** data type is uhdas making directory /home/data/EX1708_03/proc/os38nb/adcpdb making directory /home/data/EX1708_03/proc/os38nb/cal making directory /home/data/EX1708_03/proc/os38nb/cal/watertrk making directory /home/data/EX1708_03/proc/os38nb/cal/botmtrk making directory /home/data/EX1708_03/proc/os38nb/cal/heading making directory /home/data/EX1708_03/proc/os38nb/cal/rotate making directory /home/data/EX1708_03/proc/os38nb/contour making directory /home/data/EX1708_03/proc/os38nb/edit making directory /home/data/EX1708_03/proc/os38nb/grid making directory /home/data/EX1708_03/proc/os38nb/load making directory /home/data/EX1708_03/proc/os38nb/nav making directory /home/data/EX1708_03/proc/os38nb/quality making directory /home/data/EX1708_03/proc/os38nb/ping making directory /home/data/EX1708_03/proc/os38nb/scan making directory /home/data/EX1708_03/proc/os38nb/stick making directory /home/data/EX1708_03/proc/os38nb/vector - copying additional files for data type uhdas - config files for raw processing are in /home/data/EX1708_03/proc/os38nb/config copying config files using this wildcard expansion: /home/data/EX1708_03/raw/config/EX1708_03* 2017/09/15 15:53:16 - Entering buildLoggers 2017/09/15 15:53:16 - Created output directory with mode 775 2017/09/15 15:53:16 - Created output directory with mode 775 2017/09/15 15:53:16 - Created output directory with mode 775 2017/09/15 15:53:16 - Created output directory with mode 775 2017/09/15 15:53:16 - Created output directory with mode 775 2017/09/15 15:53:16 - >>>> C_ss enabled <<<<< 2017/09/15 15:53:16 - Leaving buildLoggers 2017/09/15 15:53:16 - Start Cruise 2017/09/15 15:53:26 - enter StartLogging, resume = 2017/09/15 15:53:26 - 0 2017/09/15 15:53:29 - TS? 2017/09/15 15:53:29 - TS17/09/15, 15:53:29 2017/09/15 15:53:29 - CR1 2017/09/15 15:53:29 - CL0 2017/09/15 15:53:29 - WD 111 000 000 2017/09/15 15:53:29 - TE00:00:00.00 2017/09/15 15:53:29 - CF11110 2017/09/15 15:53:29 - EX00000 2017/09/15 15:53:29 - EZ1011101 2017/09/15 15:53:29 - CL0 2017/09/15 15:53:30 - WP1 2017/09/15 15:53:30 - WN70 2017/09/15 15:53:30 - WS200 2017/09/15 15:53:30 - WF1000 2017/09/15 15:53:30 - BP0 2017/09/15 15:53:30 - BX2000 2017/09/15 15:53:30 - WB0 2017/09/15 15:53:30 - WV550 2017/09/15 15:53:30 - TP00:00.80 2017/09/15 15:53:30 - B? 2017/09/15 15:53:30 - W? 2017/09/15 15:53:31 - E? 2017/09/15 15:53:32 - C? 2017/09/15 15:53:33 - T? 2017/09/15 15:53:34 - PS0 2017/09/15 15:53:36 - In whbb_check_D_params 2017/09/15 15:53:36 - CF11110 2017/09/15 15:53:48 - term 1 ADCP os38 startup failure 2017/09/15 15:53:48 - Traceback (most recent call last): 2017/09/15 15:53:48 - File "/usr/local/lib/python2.7/dist-packages/uhdas/uhdas/DAS.py", line 356, in StartLogging 2017/09/15 15:53:48 - term.start_binary(cmdlist) 2017/09/15 15:53:48 - File "/usr/local/lib/python2.7/dist-packages/uhdas/serial/oswh_term.py", line 234, in start_binary 2017/09/15 15:53:48 - self.wakeup() 2017/09/15 15:53:48 - File "/usr/local/lib/python2.7/dist-packages/uhdas/serial/oswh_term.py", line 155, in wakeup 2017/09/15 15:53:48 - self.slow_wakeup() 2017/09/15 15:53:48 - File "/usr/local/lib/python2.7/dist-packages/uhdas/serial/oswh_term.py", line 126, in slow_wakeup 2017/09/15 15:53:48 - self.waitfor(b'>', timeout=3, quiet=0.5) 2017/09/15 15:53:48 - File "/usr/local/lib/python2.7/dist-packages/uhdas/serial/tk_terminal.py", line 286, in waitfor 2017/09/15 15:53:48 - raise Timeout 2017/09/15 15:53:48 - Timeout 2017/09/15 15:53:54 - Entering Loggers.start_logging 2017/09/15 15:53:54 - /usr/local/bin/ser_bin -y 2017 -P ttyUSB4 -b 38400 -d /home/data/EX1708_03/raw/wh300 -i /tmp/SerialLogger/inpipe.ttyUSB4 -o /tmp/SerialLogger/outpipe.ttyUSB4 -T 1505490834 -f ex -F -m 1 -H 2 -e raw -rlE -c -O -I -Z tcp://127.0.0.1:38010 2> /tmp/stderr.ttyUSB4 & 2017/09/15 15:53:54 - /usr/local/bin/ser_bin -y 2017 -P ttyUSB5 -b 38400 -d /home/data/EX1708_03/raw/os38 -i /tmp/SerialLogger/inpipe.ttyUSB5 -o /tmp/SerialLogger/outpipe.ttyUSB5 -T 1505490834 -f ex -F -m 1 -H 2 -e raw -rlE -c -O -I 2> /tmp/stderr.ttyUSB5 & 2017/09/15 15:53:54 - /usr/local/bin/ser_asc -y 2017 -P ttyUSB3 -b 9600 -d /home/data/EX1708_03/raw/cnav -i /tmp/SerialLogger/inpipe.ttyUSB3 -o /tmp/SerialLogger/outpipe.ttyUSB3 -T 1505490834 -f ex -F -m 1 -H 2 -e gps -tc '' 2> /tmp/stderr.ttyUSB3 & 2017/09/15 15:53:54 - /usr/local/bin/zmq_asc -y 2017 -Z tcp://127.0.0.1:38000 -d /home/data/EX1708_03/raw/posmv -i /tmp/SerialLogger/inpipe.tcp:--127.0.0.1:38000 -o /tmp/SerialLogger/outpipe.tcp:--127.0.0.1:38000 -T 1505490834 -f ex -F -m 1 -H 2 -e pmv '$GPGGA' '$PASHR' 2> /tmp/stderr.tcp:--127.0.0.1:38000 & 2017/09/15 15:53:55 - /usr/local/bin/ser_asc -y 2017 -P ttyUSB0 -b 4800 -d /home/data/EX1708_03/raw/gyro -i /tmp/SerialLogger/inpipe.ttyUSB0 -o /tmp/SerialLogger/outpipe.ttyUSB0 -T 1505490834 -f ex -F -m 1 -H 2 -e hdg -tc -s5 '$HEHDT' 2> /tmp/stderr.ttyUSB0 & 2017/09/15 15:53:55 - Leaving Loggers.start_logging 2017/09/15 15:53:55 - Updated SVs_present from SVs and wrote cmds 2017/09/15 15:53:55 - 0 2017/09/15 15:53:55 - Updated SVs_present from SVs and wrote cmds 2017/09/15 15:53:55 - 1 2017/09/15 15:53:55 - Running /usr/local/currents/bin/DAS_while_logging.py 2017/09/15 15:53:55 - Exiting StartLogging 2017/09/15 15:55:03 - Entering StopLogging 2017/09/15 15:55:06 - Loggers.check_stop_logging: all log processes ended 2017/09/15 15:55:09 - TS? 2017/09/15 15:55:21 - Wakeup of os38 timed out. 2017/09/15 15:55:21 - Removed /home/adcp/flags/DAS.logging 2017/09/15 15:55:21 - Exiting StopLogging 2017/09/15 15:59:06 - enter StartLogging, resume = 2017/09/15 15:59:06 - 0 2017/09/15 15:59:09 - TS? 2017/09/15 15:59:09 - TS17/09/15, 15:59:09 2017/09/15 15:59:09 - CR1 2017/09/15 15:59:09 - CL0 2017/09/15 15:59:10 - WD 111 000 000 2017/09/15 15:59:10 - TE00:00:00.00 2017/09/15 15:59:10 - CF11110 2017/09/15 15:59:10 - EX00000 2017/09/15 15:59:10 - EZ1011101 2017/09/15 15:59:10 - CL0 2017/09/15 15:59:10 - WP1 2017/09/15 15:59:10 - WN70 2017/09/15 15:59:10 - WS200 2017/09/15 15:59:10 - WF1000 2017/09/15 15:59:10 - BP0 2017/09/15 15:59:10 - BX2000 2017/09/15 15:59:10 - WB0 2017/09/15 15:59:10 - WV550 2017/09/15 15:59:10 - TP00:00.80 2017/09/15 15:59:10 - B? 2017/09/15 15:59:11 - W? 2017/09/15 15:59:12 - E? 2017/09/15 15:59:12 - C? 2017/09/15 15:59:13 - T? 2017/09/15 15:59:15 - PS0 2017/09/15 15:59:16 - In whbb_check_D_params 2017/09/15 15:59:16 - CF11110 2017/09/15 15:59:17 - Entering Loggers.start_logging 2017/09/15 15:59:17 - /usr/local/bin/ser_bin -y 2017 -P ttyUSB4 -b 38400 -d /home/data/EX1708_03/raw/wh300 -i /tmp/SerialLogger/inpipe.ttyUSB4 -o /tmp/SerialLogger/outpipe.ttyUSB4 -T 1505491157 -f ex -F -m 1 -H 2 -e raw -rlE -c -O -I -Z tcp://127.0.0.1:38010 2> /tmp/stderr.ttyUSB4 & 2017/09/15 15:59:18 - /usr/local/bin/ser_asc -y 2017 -P ttyUSB3 -b 9600 -d /home/data/EX1708_03/raw/cnav -i /tmp/SerialLogger/inpipe.ttyUSB3 -o /tmp/SerialLogger/outpipe.ttyUSB3 -T 1505491157 -f ex -F -m 1 -H 2 -e gps -tc '' 2> /tmp/stderr.ttyUSB3 & 2017/09/15 15:59:18 - /usr/local/bin/zmq_asc -y 2017 -Z tcp://127.0.0.1:38000 -d /home/data/EX1708_03/raw/posmv -i /tmp/SerialLogger/inpipe.tcp:--127.0.0.1:38000 -o /tmp/SerialLogger/outpipe.tcp:--127.0.0.1:38000 -T 1505491157 -f ex -F -m 1 -H 2 -e pmv '$GPGGA' '$PASHR' 2> /tmp/stderr.tcp:--127.0.0.1:38000 & 2017/09/15 15:59:18 - /usr/local/bin/ser_asc -y 2017 -P ttyUSB0 -b 4800 -d /home/data/EX1708_03/raw/gyro -i /tmp/SerialLogger/inpipe.ttyUSB0 -o /tmp/SerialLogger/outpipe.ttyUSB0 -T 1505491157 -f ex -F -m 1 -H 2 -e hdg -tc -s5 '$HEHDT' 2> /tmp/stderr.ttyUSB0 & 2017/09/15 15:59:18 - Leaving Loggers.start_logging 2017/09/15 15:59:18 - Updated SVs_present from SVs and wrote cmds 2017/09/15 15:59:18 - 0 2017/09/15 15:59:18 - Updated SVs_present from SVs and wrote cmds 2017/09/15 15:59:18 - 1 2017/09/15 15:59:18 - Running /usr/local/currents/bin/DAS_while_logging.py 2017/09/15 15:59:18 - Exiting StartLogging 2017/09/15 16:12:59 - Entering StopLogging 2017/09/15 16:13:01 - Loggers.check_stop_logging: all log processes ended 2017/09/15 16:13:05 - TS? 2017/09/15 16:13:05 - Removed /home/adcp/flags/DAS.logging 2017/09/15 16:13:05 - Exiting StopLogging 2017/09/15 16:13:16 - Ready to destroy_loggers. 2017/09/15 16:13:16 - After destroy_loggers. 2017/09/15 16:13:16 - cp -au /home/adcp/log/* /home/data/EX1708_03/raw/log/