Build failed in Hudson: jboss-aop-branch_2_1_jbossas-5.0.0.GA #8

See http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... Changes: [stalep] only build reference docs when we do a release [stalep] [JBAOP-723] changed it to use class if class != null. [flavia.rainone(a)jboss.com] [JBAOP-730] Replaced the old cflow precedence algorithm by an algorithm that uses a graph to determine precedence, avoiding repetition of the same advice on the resulting overall precedence order. [flavia.rainone(a)jboss.com] [JBAOP-726] Added tests that reproduce the bug to precedence package. [flavia.rainone(a)jboss.com] [JBAOP-726] Added svn Revision property. [flavia.rainone(a)jboss.com] [JBAOP-726] Merged current version of arguments tests with the old version. Normally, the previous commit would have been rolled back, but given that the new version of those tests uncovered a new bug, I'm keeping the new version, and merging it with the old one. [flavia.rainone(a)jboss.com] [JBAOP-726] Replaced all collections at ClassifiedBindingAndPointcutCollection (ConcurrentSet and CopyOnWriteArrayList) by plain old ArrayLists. This commit nullifies JBAOP-720. [flavia.rainone(a)jboss.com] [JBAOP-567] Now that constructortarget reproduces the bug, it should be kept out of the 2.0.x-compliance-tests, or else those tests start failing. ------------------------------------------ [...truncated 85620 lines...] + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=29407 + grep -q '^HTTP/.* 200 OK$' + wait 29407 + kill 29408 + (( 120 > 1277 - 1209 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + telnet localhost 8080 + TELNET=29426 + grep -q '^HTTP/.* 200 OK$' + sleep 5 + wait 29426 + kill 29427 + (( 120 > 1282 - 1209 )) + telnet localhost 8080 + TELNET=29463 + wait 29463 + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + kill 29464 + (( 120 > 1287 - 1209 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=29485 + wait 29485 + grep -q '^HTTP/.* 200 OK$' + kill 29486 + (( 120 > 1292 - 1209 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=29523 + grep -q '^HTTP/.* 200 OK$' + wait 29523 + kill 29524 + (( 120 > 1297 - 1209 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + TELNET=29555 + grep -q '^HTTP/.* 200 OK$' + wait 29555 + telnet localhost 8080 + kill 29556 + (( 120 > 1302 - 1209 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=29576 + wait 29576 + kill 29577 + (( 120 > 1307 - 1209 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=29604 + wait 29604 + grep -q '^HTTP/.* 200 OK$' + kill 29605 + (( 120 > 1312 - 1209 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=29637 + wait 29637 + grep -q '^HTTP/.* 200 OK$' + kill 29638 + (( 120 > 1317 - 1209 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=29663 + wait 29663 + grep -q '^HTTP/.* 200 OK$' + kill 29664 + (( 120 > 1322 - 1209 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + TELNET=29684 + grep -q '^HTTP/.* 200 OK$' + telnet localhost 8080 + wait 29684 + kill 29685 + exit 0 + echo Server started successfully. Server started successfully. + cd http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... + bash build.sh test -Dtest=aop Searching for build.xml ... Buildfile: http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... test: [mkdir] Created dir: http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... [junit] Running org.jboss.test.aop.test.AOPUnitTestCase [junit] Tests run: 12, Failures: 0, Errors: 0, Time elapsed: 19.78 sec [junit] Running org.jboss.test.aop.test.AnnotatedTestCase [junit] Tests run: 13, Failures: 0, Errors: 0, Time elapsed: 5.965 sec [junit] Running org.jboss.test.aop.test.DeployersInitialTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 2.031 sec [junit] Running org.jboss.test.aop.test.ExtenderTestCase [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.391 sec [junit] Running org.jboss.test.aop.test.JMXUnitTestCase [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.109 sec [junit] Running org.jboss.test.aop.test.MicrocontainerDefaultAnnotationUnitTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 1.173 sec [junit] Running org.jboss.test.aop.test.MicrocontainerJMXUnitTestCase [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 1.61 sec [junit] Running org.jboss.test.aop.test.NotWovenScopedDependencyUnitTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 3.611 sec [junit] Running org.jboss.test.aop.test.ObservableUnitTestCase [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 12.299 sec [junit] Running org.jboss.test.aop.test.ProxyCacheTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 70.065 sec [junit] Running org.jboss.test.aop.test.RemotingUnitTestCase [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 14.234 sec [junit] Running org.jboss.test.aop.test.ScopedAnnotatedTestCase [junit] Tests run: 0, Failures: 0, Errors: 1, Time elapsed: 0.819 sec [junit] Test org.jboss.test.aop.test.ScopedAnnotatedTestCase FAILED [junit] Running org.jboss.test.aop.test.ScopedAttachUnitTestCase [junit] Tests run: 0, Failures: 0, Errors: 1, Time elapsed: 0.793 sec [junit] Test org.jboss.test.aop.test.ScopedAttachUnitTestCase FAILED [junit] Running org.jboss.test.aop.test.ScopedEarWithClassesInWebInfTestCase [junit] Tests run: 0, Failures: 0, Errors: 1, Time elapsed: 0.778 sec [junit] Test org.jboss.test.aop.test.ScopedEarWithClassesInWebInfTestCase FAILED [junit] Running org.jboss.test.aop.test.ScopedExtenderBaseLoadedScopedNoParentDelegationTestCase [junit] Tests run: 0, Failures: 0, Errors: 1, Time elapsed: 0.879 sec [junit] Test org.jboss.test.aop.test.ScopedExtenderBaseLoadedScopedNoParentDelegationTestCase FAILED [junit] Running org.jboss.test.aop.test.ScopedExtenderBaseLoadedScopedParentDelegationTestCase [junit] Tests run: 0, Failures: 0, Errors: 1, Time elapsed: 0.951 sec [junit] Test org.jboss.test.aop.test.ScopedExtenderBaseLoadedScopedParentDelegationTestCase FAILED [junit] Running org.jboss.test.aop.test.ScopedExtenderBaseNotLoadedScopedNoParentDelegationTestCase [junit] Tests run: 0, Failures: 0, Errors: 1, Time elapsed: 0.77 sec [junit] Test org.jboss.test.aop.test.ScopedExtenderBaseNotLoadedScopedNoParentDelegationTestCase FAILED [junit] Running org.jboss.test.aop.test.ScopedExtenderBaseNotLoadedScopedParentDelegationTestCase [junit] Tests run: 0, Failures: 0, Errors: 1, Time elapsed: 0.852 sec [junit] Test org.jboss.test.aop.test.ScopedExtenderBaseNotLoadedScopedParentDelegationTestCase FAILED [junit] Running org.jboss.test.aop.test.ScopedUnitTestCase [junit] Tests run: 0, Failures: 0, Errors: 1, Time elapsed: 0.711 sec [junit] Test org.jboss.test.aop.test.ScopedUnitTestCase FAILED [junit] Running org.jboss.test.aop.test.ScopedWovenDependencyTestCase [junit] Tests run: 0, Failures: 0, Errors: 1, Time elapsed: 0.839 sec [junit] Test org.jboss.test.aop.test.ScopedWovenDependencyTestCase FAILED [junit] Running org.jboss.test.aop.test.SecurityUnitTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 19.954 sec [junit] Running org.jboss.test.aop.test.SimpleBeanUnitTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 11.582 sec [junit] Running org.jboss.test.aop.test.TxLockUnitTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 24.4 sec [junit] Running org.jboss.test.aop.test.TxUnitTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 17.668 sec [junit] Running org.jboss.test.aop.test.VersionedObjectUnitTestCase [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 16.015 sec BUILD SUCCESSFUL Total time: 5 minutes 28 seconds + stop-jboss -p http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... -b localhost + local flag= + local OPTIND=1 + getopts p:b:t:m: flag + case $flag in + local JBOSS_SHUTDOWN_PATH=http://hudson.qa.jboss.com/hudson/job/jboss-aop-branc... + getopts p:b:t:m: flag + case $flag in + local JBOSS_SERVER_BIND_ADDR=localhost + getopts p:b:t:m: flag + [[ -f http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... ]] + [[ -f http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... ]] + [[ -f http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... ]] + [[ -f http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... ]] + [[ -r http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... ]] + [[ -s http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... ]] + JBOSS_SHUTDOWN_PATH=http://hudson.qa.jboss.com/hudson/job/jboss-aop-branc... + chmod 770 http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... + [[ -n localhost ]] + [[ -n '' ]] + local JBOSS_SERVER_SHUTDOWN_MODE=S + [[ '' -gt 0 ]] + local JBOSS_SERVER_TIMEOUT=90 + local 'STOP_OPTS=-S -s localhost:1099' + echo Stopping server on IP localhost Stopping server on IP localhost + echo With command '"http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.GA/ws/jboss-5.0.0.GA-src/build/output/jboss-5.0.0.GA/server/all/../../bin/shutdown.sh -S -s localhost:1099"' With command "http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.GA/ws/jboss-5.0.0.GA-src/build/output/jboss-5.0.0.GA/server/all/../../bin/shutdown.sh -S -s localhost:1099" + http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... -S -s localhost:1099 Shutdown message has been posted to the server. Server shutdown may take a while - check logfiles for completion ++ exec ++ IFS== ++ read IP ServerLog ++ [[ localhost == \l\o\c\a\l\h\o\s\t ]] ++ LOG=http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.... ++ read IP ServerLog ++ echo http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... + JBOSS_SERVER_LOG=http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2... + echo Checking Log '"http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.GA/ws/server_all.log"' for shutdown. Checking Log "http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.GA/ws/server_all.log" for shutdown. + echo Timeout will be 90 seconds. Timeout will be 90 seconds. + local interval=5 + local i=0 + (( 0 < 90 )) + i=5 ++ tail -1 http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... + [[ 12:36:06,731 INFO [Http11Protocol] Pausing Coyote HTTP/1.1 on http-localhost%2F127.0.0.1-8080 == \H\a\l\t\i\n\g\ \V\M ]] + sleep 5 + (( 5 < 90 )) + i=10 ++ tail -1 http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... + [[ 12:36:11,723 INFO [HypersonicDatabase] Database standalone closed clean == \H\a\l\t\i\n\g\ \V\M ]] + sleep 5 + (( 10 < 90 )) + i=15 ++ tail -1 http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... + [[ 12:36:11,723 INFO [HypersonicDatabase] Database standalone closed clean == \H\a\l\t\i\n\g\ \V\M ]] + sleep 5 + (( 15 < 90 )) + i=20 ++ tail -1 http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... + [[ Halting VM == \H\a\l\t\i\n\g\ \V\M ]] + return 0 + rm -rf output/reports/TEST-org.jboss.test.aop.test.ScopedAnnotatedTestCase.xml output/reports/TEST-org.jboss.test.aop.test.ScopedAttachUnitTestCase.xml output/reports/TEST-org.jboss.test.aop.test.ScopedEarWithClassesInWebInfTestCase.xml output/reports/TEST-org.jboss.test.aop.test.ScopedExtenderBaseLoadedScopedNoParentDelegationTestCase.xml output/reports/TEST-org.jboss.test.aop.test.ScopedExtenderBaseLoadedScopedParentDelegationTestCase.xml output/reports/TEST-org.jboss.test.aop.test.ScopedExtenderBaseNotLoadedScopedNoParentDelegationTestCase.xml output/reports/TEST-org.jboss.test.aop.test.ScopedExtenderBaseNotLoadedScopedParentDelegationTestCase.xml output/reports/TEST-org.jboss.test.aop.test.ScopedUnitTestCase.xml output/reports/TEST-org.jboss.test.aop.test.ScopedWovenDependencyTestCase.xml + bash build.sh tests-aop-scoped Searching for build.xml ... Buildfile: http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... tests-aop-scoped: [echo] creating scoped-aop-jdk50 config [copy] Copying 340 files to http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... [copy] Copied 105 empty directories to 4 empty directories under http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... [echo] Overwriting config descriptors [copy] Warning: http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... not found. [server:start] Starting server "scoped-aop-jdk50", with command (start timeout is 120 seconds ): [server:start] /qa/tools/opt/jdk1.5.0_15/bin/java -cp http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... :/qa/tools/opt/jdk1.5.0_15/lib/tools.jar -Xms128m -Xmx512m -XX:MaxPermSize=512m -XX:+HeapDumpOnOutOfMemoryError -javaagent:pluggable-instrumentor.jar -Djava.net.preferIPv4Stack=true -Djava.endorsed.dirs=http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.GA/ws/jboss-5.0.0.GA-src/build/output/jboss-5.0.0.GA/lib/endorsed -Djgroups.udp.ip_ttl=0 org.jboss.Main -c scoped-aop-jdk50 -b localhost -g DefaultDomain [server:start] Failed to start server "scoped-aop-jdk50" before timeout. Destroying the process. BUILD FAILED http://hudson.qa.jboss.com/hudson/job/jboss-aop-branch_2_1_jbossas-5.0.0.... :2669: Error starting server "scoped-aop-jdk50": Server failed to start in time; see logs. Total time: 2 minutes 9 seconds Terminating xvnc [jboss-aop-branch_2_1_jbossas-5.0.0.GA] $ vncserver -kill :18 Killing Xvnc process ID 25764 [jboss-aop-branch_2_1_jbossas-5.0.0.GA] $ bash -xe /tmp/hudson2032876125771701095.sh + kill 25744 Recording test results