See http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/2/ ------------------------------------------ [...truncated 24988 lines...] ++ basename http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/w... + local JBOSS_SERVER_NAME=all + [[ -n localhost ]] + [[ -n '' ]] + local JBOSS_SERVER_MCAST_ADDR=227.43.89.26 + [[ '' -gt 0 ]] + local JBOSS_SERVER_TIMEOUT=120 + [[ -n '' ]] + local JBOSS_SERVER_HA_NAME= + local 'START_OPTS=-c all -b localhost -u 227.43.89.26 ' + cd http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/w... + cd ../../bin + chmod 755 run.sh + local 'JBOSS_SERVER_COMMAND=./run.sh -c all -b localhost -u 227.43.89.26 ' + local JBOSS_SERVER_LOG=http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbo... + echo Starting server http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/w... Starting server http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/w... ++ pwd + echo From directory '"http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/ws/Branch_5_x/build/output/jboss-5.1.0.GA/bin"' + echo With Command '"./run.sh -c all -b localhost -u 227.43.89.26 "' With Command "./run.sh -c all -b localhost -u 227.43.89.26 " + echo Log to http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/w... Log to http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/w... + [[ -f http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/w... ]] + echo localhost=http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-br... + echo Waiting server to start. Waiting server to start. + echo Timeout should be slightly more than 120 seconds. Timeout should be slightly more than 120 seconds. + INT=5 + STARTSECONDS=1437 + (( 120 > 1437 - 1437 )) + ./run.sh -c all -b localhost -u 227.43.89.26 + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + TELNET=21364 + wait 21364 + grep -q '^HTTP/.* 200 OK$' + telnet localhost 8080 + kill 21367 + (( 120 > 1442 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21413 + wait 21413 + grep -q '^HTTP/.* 200 OK$' + kill 21414 + (( 120 > 1447 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21431 + wait 21431 + grep -q '^HTTP/.* 200 OK$' + kill 21432 + (( 120 > 1452 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21448 + wait 21448 + grep -q '^HTTP/.* 200 OK$' + kill 21449 + (( 120 > 1459 - 1437 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21467 + wait 21467 + kill 21470 + (( 120 > 1464 - 1437 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + TELNET=21490 + wait 21490 + telnet localhost 8080 + kill 21493 + (( 120 > 1469 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21508 + wait 21508 + grep -q '^HTTP/.* 200 OK$' + kill 21509 + (( 120 > 1475 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21524 + wait 21524 + grep -q '^HTTP/.* 200 OK$' + kill 21525 + (( 120 > 1480 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21543 + wait 21543 + grep -q '^HTTP/.* 200 OK$' + kill 21544 + (( 120 > 1485 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + TELNET=21560 + grep -q '^HTTP/.* 200 OK$' + wait 21560 + telnet localhost 8080 + kill 21563 + (( 120 > 1490 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21578 + wait 21578 + grep -q '^HTTP/.* 200 OK$' + kill 21579 + (( 120 > 1495 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21593 + wait 21593 + grep -q '^HTTP/.* 200 OK$' + kill 21594 + (( 120 > 1501 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21610 + wait 21610 + grep -q '^HTTP/.* 200 OK$' + kill 21611 + (( 120 > 1506 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21629 + wait 21629 + grep -q '^HTTP/.* 200 OK$' + kill 21630 + (( 120 > 1511 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + grep -q '^HTTP/.* 200 OK$' + telnet localhost 8080 + TELNET=21643 + wait 21643 + kill 21645 + (( 120 > 1516 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21675 + wait 21675 + grep -q '^HTTP/.* 200 OK$' + kill 21676 + (( 120 > 1521 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21710 + wait 21710 + grep -q '^HTTP/.* 200 OK$' + kill 21711 + (( 120 > 1527 - 1437 )) + telnet localhost 8080 + TELNET=21741 + wait 21741 + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + grep -q '^HTTP/.* 200 OK$' + kill 21742 + (( 120 > 1532 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + grep -q '^HTTP/.* 200 OK$' + telnet localhost 8080 + TELNET=21771 + wait 21771 + kill 21775 + (( 120 > 1537 - 1437 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + TELNET=21803 + wait 21803 + grep -q '^HTTP/.* 200 OK$' + telnet localhost 8080 /home/hudson/config_repository/scripts/function.d/start-jboss: line 115: 21802 Done ( echo -e "GET / HTTP/1.0\nConnection: close\n\n"; sleep $INT ) 21803 Terminated | telnet $JBOSS_SERVER_BIND_ADDR 8080 2>/dev/null + kill 21806 + (( 120 > 1542 - 1437 )) + telnet localhost 8080 + TELNET=21832 + wait 21832 + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 /home/hudson/config_repository/scripts/function.d/start-jboss: line 115: 21831 Done ( echo -e "GET / HTTP/1.0\nConnection: close\n\n"; sleep $INT ) 21832 Terminated | telnet $JBOSS_SERVER_BIND_ADDR 8080 2>/dev/null + kill 21833 + (( 120 > 1547 - 1437 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=21864 + wait 21864 /home/hudson/config_repository/scripts/function.d/start-jboss: line 115: 21862 Done ( echo -e "GET / HTTP/1.0\nConnection: close\n\n"; sleep $INT ) 21864 Terminated | telnet $JBOSS_SERVER_BIND_ADDR 8080 2>/dev/null + kill 21865 + (( 120 > 1552 - 1437 )) + telnet localhost 8080 + TELNET=21883 + wait 21883 + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + grep -q '^HTTP/.* 200 OK$' + kill 21884 + (( 120 > 1558 - 1437 )) + echo Server failed to start in time. Terminating. Server failed to start in time. Terminating. + exit 1 + kill-jboss ++ uname -s + [[ Linux == \L\i\n\u\x ]] + ps -eaf --columns 2200 + grep -v grep + awk '{ print $2; }' + xargs kill + grep run.jar + sleep 10 + ps -eaf --columns 2200 + grep -v grep + awk '{ print $2; }' + xargs kill -9 + grep run.jar + return 1 Terminating xvnc [jboss-aop-head-jbossas-branch_5_x] $ vncserver -kill :15 Killing Xvnc process ID 17269 kill 17269: No such process [jboss-aop-head-jbossas-branch_5_x] $ bash -xe /tmp/hudson2336967223037933300.sh + kill 17249 Recording test results

See http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/4... Changes: [kabir.khan(a)jboss.com] Update release notes [flavia.rainone(a)jboss.com] [JBAOP-722] Removed echo that was added just for debugging purposes. [flavia.rainone(a)jboss.com] [JBAOP-722] Util.matchesParameters methods were changed, so that now the constructor of the specified scenario will be treated as being the default one during matching. ------------------------------------------ [...truncated 25075 lines...] backup.lib: [echo] Backing up existing lib directory to /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/lib-20090508-131400.bak [copy] Copying 59 files to /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/lib-20090508-131400.bak backup.client.lib: [echo] Backing up existing /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/client/jboss-aop-client.jar to /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/client/jboss-aop-client.bak [copy] Copying 1 file to /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/client install.lib: [copy] Copying 1 file to /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/trunk/build/output/jboss-aop-2.1.0-SNAPSHOT/jboss-50-install backup.deployer: [mkdir] Created dir: /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/server/all/deployers.bak [echo] Backing up existing aop deployer to ${old.deployer} [copy] Copying 7 files to /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/server/all/deployers.bak/jboss-aop-jboss5.deployer-20090508-131400.bak install.deployer: install.bin: [copy] Copying 2 files to /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/bin install: BUILD SUCCESSFUL Total time: 3 seconds + start-jboss -p /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/server/all -b localhost + local flag= + local OPTIND=1 + getopts p:b:u:g:t: flag + case $flag in + local JBOSS_SERVER_PATH=/qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/server/all + getopts p:b:u:g:t: flag + case $flag in + local JBOSS_SERVER_BIND_ADDR=localhost + getopts p:b:u:g:t: flag + [[ -d /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/server/all ]] ++ basename /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/server/all + local JBOSS_SERVER_NAME=all + [[ -n localhost ]] + [[ -n '' ]] + local JBOSS_SERVER_MCAST_ADDR=227.43.88.245 + [[ '' -gt 0 ]] + local JBOSS_SERVER_TIMEOUT=120 + [[ -n '' ]] + local JBOSS_SERVER_HA_NAME= + local 'START_OPTS=-c all -b localhost -u 227.43.88.245 ' + cd /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/server/all + cd ../../bin + chmod 755 run.sh + local 'JBOSS_SERVER_COMMAND=./run.sh -c all -b localhost -u 227.43.88.245 ' + local JBOSS_SERVER_LOG=http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbo... + echo Starting server /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/server/all Starting server /qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/server/all ++ pwd + echo From directory '"/qa/services/hudson/hudson_workspace/workspace/jboss-aop-head-jbossas-branch_5_x/Branch_5_x/build/output/jboss-5.1.0.GA/bin"' + echo With Command '"./run.sh -c all -b localhost -u 227.43.88.245 "' With Command "./run.sh -c all -b localhost -u 227.43.88.245 " + echo Log to http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/w... Log to http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/w... + [[ -f http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-branch_5_x/w... ]] + echo localhost=http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-br... + ./run.sh -c all -b localhost -u 227.43.88.245 + echo Waiting server to start. Waiting server to start. + echo Timeout should be slightly more than 120 seconds. Timeout should be slightly more than 120 seconds. + INT=5 + STARTSECONDS=3730 + (( 120 > 3730 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28356 + grep -q '^HTTP/.* 200 OK$' + wait 28356 + kill 28357 + (( 120 > 3735 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28406 + wait 28406 + grep -q '^HTTP/.* 200 OK$' + kill 28407 + (( 120 > 3741 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28424 + wait 28424 + grep -q '^HTTP/.* 200 OK$' + kill 28425 + (( 120 > 3747 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28441 + wait 28441 + grep -q '^HTTP/.* 200 OK$' + kill 28442 + (( 120 > 3752 - 3730 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28456 + wait 28456 + kill 28458 + (( 120 > 3757 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28481 + wait 28481 + grep -q '^HTTP/.* 200 OK$' + kill 28482 + (( 120 > 3763 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28501 + wait 28501 + grep -q '^HTTP/.* 200 OK$' + kill 28502 + (( 120 > 3768 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28520 + wait 28520 + grep -q '^HTTP/.* 200 OK$' + kill 28521 + (( 120 > 3773 - 3730 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28538 + wait 28538 + kill 28539 + (( 120 > 3778 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28553 + wait 28553 + grep -q '^HTTP/.* 200 OK$' + kill 28556 + (( 120 > 3783 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28570 + grep -q '^HTTP/.* 200 OK$' + wait 28570 + kill 28571 + (( 120 > 3793 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + TELNET=28583 + wait 28583 + grep -q '^HTTP/.* 200 OK$' + telnet localhost 8080 + kill 28600 + (( 120 > 3813 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28638 + wait 28638 + grep -q '^HTTP/.* 200 OK$' + kill 28639 + (( 120 > 3818 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28656 + grep -q '^HTTP/.* 200 OK$' + wait 28656 + kill 28657 + (( 120 > 3823 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28684 + wait 28684 + grep -q '^HTTP/.* 200 OK$' + kill 28685 + (( 120 > 3828 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28710 + wait 28710 + grep -q '^HTTP/.* 200 OK$' + kill 28711 + (( 120 > 3833 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28739 + wait 28739 + grep -q '^HTTP/.* 200 OK$' + kill 28740 + (( 120 > 3838 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + TELNET=28770 + grep -q '^HTTP/.* 200 OK$' + telnet localhost 8080 + wait 28770 + kill 28771 + (( 120 > 3844 - 3730 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=28806 + wait 28806 + grep -q '^HTTP/.* 200 OK$' /home/hudson/config_repository/scripts/function.d/start-jboss: line 115: 28803 Done ( echo -e "GET / HTTP/1.0\nConnection: close\n\n"; sleep $INT ) 28806 Terminated | telnet $JBOSS_SERVER_BIND_ADDR 8080 2> /dev/null + kill 28807 + (( 120 > 3850 - 3730 )) + echo Server failed to start in time. Terminating. Server failed to start in time. Terminating. + exit 1 + kill-jboss ++ uname -s + [[ Linux == \L\i\n\u\x ]] + ps -eaf --columns 2200 + grep run.jar + grep -v grep + awk '{ print $2; }' + xargs kill + sleep 10 + ps -eaf --columns 2200 + grep run.jar + grep -v grep + awk '{ print $2; }' + xargs kill -9 + return 1 Terminating xvnc [jboss-aop-head-jbossas-branch_5_x] $ vncserver -kill :15 Killing Xvnc process ID 19337 kill 19337: No such process [jboss-aop-head-jbossas-branch_5_x] $ bash -xe /tmp/hudson7572409188587807682.sh + kill 19312 Recording test results