Build failed in Hudson: jboss-aop-head-jbossas-5.0.0.GA #88

See http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/88/... Changes: [flavia.rainone(a)jboss.com] [JBAOP-720] Replaced the collection on the classes Advisor, ClassifiedBindingAndPointcutCollection, UnmodifiableEmptyCollections. [flavia.rainone(a)jboss.com] [JBAOP-713] The synchronized blocks have been replaced by use of the AOPLock only. The reason for this is that using double synchronization blocks was the same as not getting the benefits of readWrite lock: synchronized{lock.lockWrite()....} synchronized{lock.lockRead()....} is the same as using synchronized{}. The use of synchronized was responsible for the deadlock scenarios we've been seeing. ------------------------------------------ [...truncated 85544 lines...] + [[ -n '' ]] + local JBOSS_SERVER_HA_NAME= + local 'START_OPTS=-c all -b localhost -u 227.43.89.33 ' + cd http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... + cd ../../bin + chmod 755 run.sh + local 'JBOSS_SERVER_COMMAND=./run.sh -c all -b localhost -u 227.43.89.33 ' + 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-5.0.0.GA/ws/... Starting server http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... ++ pwd + echo From directory '"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/bin"' + echo With Command '"./run.sh -c all -b localhost -u 227.43.89.33 "' With Command "./run.sh -c all -b localhost -u 227.43.89.33 " + echo Log to http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... Log to http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... + [[ -fws http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.0.0.GA/ws/... ]] + echo localhost=http://hudson.qa.jboss.com/hudson/job/jboss-aop-head-jbossas-5.... + ./run.sh -c all -b localhost -u 227.43.89.33 + 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=1138 + (( 120 > 1138 - 1138 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=22866 + wait 22866 + kill 22867 + (( 120 > 1143 - 1138 )) + telnet localhost 8080 + TELNET=22913 + wait 22913 + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + kill 22914 + (( 120 > 1150 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + grep -q '^HTTP/.* 200 OK$' + TELNET=22934 + wait 22934 + kill 22936 + (( 120 > 1155 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=22951 + wait 22951 + grep -q '^HTTP/.* 200 OK$' + kill 22952 + (( 120 > 1160 - 1138 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=22968 + wait 22968 + kill 22969 + (( 120 > 1166 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=22986 + grep -q '^HTTP/.* 200 OK$' + wait 22986 + kill 22987 + (( 120 > 1171 - 1138 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23003 + wait 23003 + kill 23005 + (( 120 > 1176 - 1138 )) + telnet localhost 8080 + TELNET=23027 + wait 23027 + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + kill 23028 + (( 120 > 1181 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + TELNET=23046 + grep -q '^HTTP/.* 200 OK$' + wait 23046 + telnet localhost 8080 + kill 23047 + (( 120 > 1187 - 1138 )) + telnet localhost 8080 + TELNET=23063 + wait 23063 + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + kill 23064 + (( 120 > 1192 - 1138 )) + telnet localhost 8080 + TELNET=23080 + wait 23080 + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + kill 23081 + (( 120 > 1197 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + TELNET=23095 + grep -q '^HTTP/.* 200 OK$' + telnet localhost 8080 + wait 23095 + kill 23096 + (( 120 > 1202 - 1138 )) + telnet localhost 8080 + TELNET=23115 + wait 23115 + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + kill 23116 + (( 120 > 1202 - 1138 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23122 + wait 23122 + kill 23123 + (( 120 > 1208 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23138 + wait 23138 + grep -q '^HTTP/.* 200 OK$' + kill 23139 + (( 120 > 1213 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23156 + wait 23156 + grep -q '^HTTP/.* 200 OK$' + kill 23157 + (( 120 > 1218 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23173 + wait 23173 + grep -q '^HTTP/.* 200 OK$' + kill 23174 + (( 120 > 1224 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23192 + wait 23192 + grep -q '^HTTP/.* 200 OK$' + kill 23193 + (( 120 > 1229 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23208 + wait 23208 + grep -q '^HTTP/.* 200 OK$' + kill 23209 + (( 120 > 1234 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23225 + wait 23225 + grep -q '^HTTP/.* 200 OK$' + kill 23226 + (( 120 > 1239 - 1138 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23257 + wait 23257 + kill 23259 + (( 120 > 1245 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23276 + wait 23276 + grep -q '^HTTP/.* 200 OK$' + kill 23277 + (( 120 > 1250 - 1138 )) + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23302 + wait 23302 + kill 23303 + (( 120 > 1255 - 1138 )) + telnet localhost 8080 + TELNET=23328 + wait 23328 + grep -q '^HTTP/.* 200 OK$' + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + kill 23329 + (( 120 > 1255 - 1138 )) + echo -e 'GET / HTTP/1.0\nConnection: close\n\n' + sleep 5 + telnet localhost 8080 + TELNET=23335 + wait 23335 + grep -q '^HTTP/.* 200 OK$' + kill 23336 + (( 120 > 1260 - 1138 )) + 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 + awk '{ print $2; }' + xargs kill + grep -v grep + sleep 10 + grep run.jar + grep -v grep + awk '{ print $2; }' + xargs kill -9 + ps -eaf --columns 2200 + return 1 [jboss-aop-head-jbossas-5.0.0.GA] $ bash -xe /tmp/hudson2522415367853615061.sh + kill 19294 Terminating xvnc [jboss-aop-head-jbossas-5.0.0.GA] $ vncserver -kill :13 Killing Xvnc process ID 19286 Recording test results