See http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/39/... Changes: [kabir.khan(a)jboss.com] [JBAOP-666] Test primitive arrays in jboss-cl pools ------------------------------------------ [...truncated 28614 lines...] + TELNET=10863 + wait 10863 + grep -q '^HTTP/.* 200 OK$' + kill 10864 + (( 120 > 1287 - 1227 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=10902 + wait 10902 + grep -q '^HTTP/.* 200 OK$' + kill 10903 + (( 120 > 1292 - 1227 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=10963 + wait 10963 + grep -q '^HTTP/.* 200 OK$' + kill 10964 + (( 120 > 1297 - 1227 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=10998 + wait 10998 + grep -q '^HTTP/.* 200 OK$' + kill 10999 + (( 120 > 1302 - 1227 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=11043 + wait 11043 + kill 11044 + (( 120 > 1308 - 1227 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + TELNET=11098 + wait 11098 + grep -q '^HTTP/.* 200 OK$' + telnet localhost 8080 + kill 11101 + (( 120 > 1313 - 1227 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=11126 + wait 11126 + grep -q '^HTTP/.* 200 OK$' /home/hudson/config_repository/scripts/function.d/start-jboss: line 115: 11125 Done ( echo -e "GET / HTTP/1.0\nConnection: close\n\n"; sleep $INT ) 11126 Terminated | telnet $JBOSS_SERVER_BIND_ADDR 8080 2>/dev/null + kill 11127 + (( 120 > 1318 - 1227 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=11148 + wait 11148 + grep -q '^HTTP/.* 200 OK$' + kill 11149 + (( 120 > 1323 - 1227 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=11200 + wait 11200 + grep -q '^HTTP/.* 200 OK$' + kill 11201 + (( 120 > 1328 - 1227 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=11219 + wait 11219 + grep -q '^HTTP/.* 200 OK$' + kill 11220 + (( 120 > 1333 - 1227 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=11242 + wait 11242 + grep -q '^HTTP/.* 200 OK$' + kill 11243 + exit 0 + echo Server started successfully. Server started successfully. + cd http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... + bash build.sh test -Dtest=aop Searching for build.xml ... Buildfile: http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... test: [mkdir] Created dir: http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... [junit] Running org.jboss.test.aop.test.AOPUnitTestCase [junit] Tests run: 12, Failures: 0, Errors: 0, Time elapsed: 20.383 sec [junit] Running org.jboss.test.aop.test.AnnotatedTestCase [junit] Tests run: 13, Failures: 0, Errors: 0, Time elapsed: 8.766 sec [junit] Running org.jboss.test.aop.test.DeployersInitialTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 3.145 sec [junit] Running org.jboss.test.aop.test.ExtenderTestCase [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.77 sec [junit] Running org.jboss.test.aop.test.JMXUnitTestCase [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.427 sec [junit] Running org.jboss.test.aop.test.MicrocontainerDefaultAnnotationUnitTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 1.558 sec [junit] Running org.jboss.test.aop.test.MicrocontainerJMXUnitTestCase [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.205 sec [junit] Running org.jboss.test.aop.test.NotWovenScopedDependencyUnitTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 5.037 sec [junit] Running org.jboss.test.aop.test.ObservableUnitTestCase [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 18.267 sec [junit] Running org.jboss.test.aop.test.ProxyCacheTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 75.948 sec [junit] Running org.jboss.test.aop.test.RemotingUnitTestCase [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 16.899 sec [junit] Running org.jboss.test.aop.test.ScopedAnnotatedTestCase [junit] Tests run: 0, Failures: 0, Errors: 1, Time elapsed: 1.249 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.736 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.599 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.607 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.59 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.541 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.611 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.742 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.805 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: 23.972 sec [junit] Running org.jboss.test.aop.test.SimpleBeanUnitTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 9.044 sec [junit] Running org.jboss.test.aop.test.TxLockUnitTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 26.584 sec [junit] Running org.jboss.test.aop.test.TxUnitTestCase [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 17.254 sec [junit] Running org.jboss.test.aop.test.VersionedObjectUnitTestCase [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 22.25 sec BUILD SUCCESSFUL Total time: 5 minutes 56 seconds + stop-jboss -p http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... -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-head-... + getopts p:b:t:m: flag + case $flag in + local JBOSS_SERVER_BIND_ADDR=localhost + getopts p:b:t:m: flag + [[ -frs http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... ]] + [[ -frs http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... ]] + [[ -frs http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... ]] + [[ -frs http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... ]] + JBOSS_SHUTDOWN_PATH=http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-... + chmod 770 http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... + [[ -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-head-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-head-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-head-jbossas-5.0.0.GA/ws/... -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-head-jbossas-5.0.0.GA... ++ read IP ServerLog ++ echo http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... + JBOSS_SERVER_LOG=http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbo... + echo Checking Log '"http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/server_all.log"' for shutdown. Checking Log "http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-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-head-jbossas-5.0.0.GA/ws/... + [[ 00:34:18,004 INFO [AjpProtocol] Stopping Coyote AJP/1.3 on ajp-localhost%2F127.0.0.1-8009 == \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-head-jbossas-5.0.0.GA/ws/... + [[ 00:34:24,381 INFO [SnmpAgentService] SNMP agent stopped == \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-head-jbossas-5.0.0.GA/ws/... + [[ 00:34:26,196 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-head-jbossas-5.0.0.GA/ws/... + [[ 00:34:26,196 INFO [HypersonicDatabase] Database standalone closed clean == \H\a\l\t\i\n\g\ \V\M ]] + sleep 5 + (( 20 < 90 )) + i=25 ++ tail -1 http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... + [[ 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-head-jbossas-5.0.0.GA/ws/... tests-aop-scoped: [echo] creating scoped-aop-jdk50 config [copy] Copying 340 files to http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... [copy] Copied 105 empty directories to 4 empty directories under http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... [echo] Overwriting config descriptors [copy] Warning: http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... 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-head-jbossas-5.0.0.GA/ws/... :/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-head-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-head-jbossas-5.0.0.GA/ws/... :2669: Error starting server "scoped-aop-jdk50": Server failed to start in time; see logs. Total time: 2 minutes 17 seconds Terminating xvnc [jboss-aop-head-jbossas-5.0.0.GA] $ vncserver -kill :21 Killing Xvnc process ID 4447 Recording test results