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