See <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/>
------------------------------------------ [...truncated 1021 lines...] + echo ### [INFO] Running test suite suite-webengine ... ### [INFO] Running test suite suite-webengine ... + java -jar selenium-server.jar -port 14440 -timeout 7200 -browserSideLog -htmlSuite *chrome http://localhost:8080/nuxeo/ <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/nuxeo-distribution-5.4/nuxeo-distribution-dm/ftest/selenium/tests/suite-webengine.html> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/nuxeo-distribution-5.4/nuxeo-distribution-dm/ftest/selenium/result-suite-webengine.html> -firefoxProfileTemplate ffprofile -userExtensions user-extensions.js 06:39:31.078 INFO - Java: Sun Microsystems Inc. 19.1-b02 06:39:31.079 INFO - OS: Linux 2.6.32-28-generic-pae i386 06:39:31.084 INFO - v2.0 [b1], with Core v2.0 [b1] 06:39:31.192 INFO - RemoteWebDriver instances should connect to: http://127.0.0.1:14440/wd/hub 06:39:31.193 INFO - Version Jetty/5.1.x 06:39:31.194 INFO - Started HttpContext[/selenium-server/driver,/selenium-server/driver] 06:39:31.195 INFO - Started HttpContext[/selenium-server,/selenium-server] 06:39:31.195 INFO - Started HttpContext[/,/] 06:39:52.336 INFO - Started org.openqa.jetty.jetty.servlet.ServletHandler@1d64c37 06:39:52.336 INFO - Started HttpContext[/wd,/wd] 06:39:52.345 INFO - Started SocketListener on 0.0.0.0:14440 06:39:52.345 INFO - Started org.openqa.jetty.jetty.Server@116ab4e 06:39:52.434 INFO - Preparing Firefox profile... 06:39:54.741 INFO - Launching Firefox... 06:39:55.426 INFO - Checking Resource aliases 06:40:01.493 INFO - Checking Resource aliases 06:40:01.494 INFO - Received posted results 06:40:01.754 INFO - Killing Firefox... 06:40:01.824 INFO - Shutting down... 06:40:01.825 INFO - Stopping Acceptor ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=14440] + [ 0 != 0 ] + echo ### [INFO] suite-webengine TEST SUCCESSFUL ### [INFO] suite-webengine TEST SUCCESSFUL + sleep 5 + echo ### [INFO] Running test suite suite-webengine-website ... ### [INFO] Running test suite suite-webengine-website ... + java -jar selenium-server.jar -port 14440 -timeout 7200 -browserSideLog -htmlSuite *chrome http://localhost:8080/nuxeo/ <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/nuxeo-distribution-5.4/nuxeo-distribution-dm/ftest/selenium/tests/suite-webengine-website.html> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/nuxeo-distribution-5.4/nuxeo-distribution-dm/ftest/selenium/result-suite-webengine-website.html> -firefoxProfileTemplate ffprofile -userExtensions user-extensions.js 06:40:07.282 INFO - Java: Sun Microsystems Inc. 19.1-b02 06:40:07.284 INFO - OS: Linux 2.6.32-28-generic-pae i386 06:40:07.288 INFO - v2.0 [b1], with Core v2.0 [b1] 06:40:07.397 INFO - RemoteWebDriver instances should connect to: http://127.0.0.1:14440/wd/hub 06:40:07.398 INFO - Version Jetty/5.1.x 06:40:07.399 INFO - Started HttpContext[/selenium-server/driver,/selenium-server/driver] 06:40:07.399 INFO - Started HttpContext[/selenium-server,/selenium-server] 06:40:07.400 INFO - Started HttpContext[/,/] 06:40:32.334 INFO - Started org.openqa.jetty.jetty.servlet.ServletHandler@25d2b2 06:40:32.334 INFO - Started HttpContext[/wd,/wd] 06:40:32.338 INFO - Started SocketListener on 0.0.0.0:14440 06:40:32.338 INFO - Started org.openqa.jetty.jetty.Server@116ab4e 06:40:32.442 INFO - Preparing Firefox profile... 06:40:34.742 INFO - Launching Firefox... 06:40:35.391 INFO - Checking Resource aliases 06:41:14.802 INFO - Checking Resource aliases 06:41:14.803 INFO - Received posted results 06:41:15.257 INFO - Killing Firefox... 06:41:15.355 INFO - Shutting down... 06:41:15.357 INFO - Stopping Acceptor ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=14440] + [ 0 != 0 ] + echo ### [INFO] suite-webengine-website TEST SUCCESSFUL ### [INFO] suite-webengine-website TEST SUCCESSFUL + sleep 5 + echo ### [INFO] Running test suite suite-webengine-tags ... ### [INFO] Running test suite suite-webengine-tags ... + java -jar selenium-server.jar -port 14440 -timeout 7200 -browserSideLog -htmlSuite *chrome http://localhost:8080/nuxeo/ <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/nuxeo-distribution-5.4/nuxeo-distribution-dm/ftest/selenium/tests/suite-webengine-tags.html> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/nuxeo-distribution-5.4/nuxeo-distribution-dm/ftest/selenium/result-suite-webengine-tags.html> -firefoxProfileTemplate ffprofile -userExtensions user-extensions.js 06:41:21.062 INFO - Java: Sun Microsystems Inc. 19.1-b02 06:41:21.064 INFO - OS: Linux 2.6.32-28-generic-pae i386 06:41:21.068 INFO - v2.0 [b1], with Core v2.0 [b1] 06:41:21.181 INFO - RemoteWebDriver instances should connect to: http://127.0.0.1:14440/wd/hub 06:41:21.183 INFO - Version Jetty/5.1.x 06:41:21.184 INFO - Started HttpContext[/selenium-server/driver,/selenium-server/driver] 06:41:21.185 INFO - Started HttpContext[/selenium-server,/selenium-server] 06:41:21.185 INFO - Started HttpContext[/,/] 06:41:21.213 INFO - Started org.openqa.jetty.jetty.servlet.ServletHandler@9e29fb 06:41:21.214 INFO - Started HttpContext[/wd,/wd] 06:41:21.232 INFO - Started SocketListener on 0.0.0.0:14440 06:41:21.232 INFO - Started org.openqa.jetty.jetty.Server@116ab4e 06:41:21.336 INFO - Preparing Firefox profile... 06:41:23.582 INFO - Launching Firefox... 06:41:24.217 INFO - Checking Resource aliases 06:41:40.181 INFO - Checking Resource aliases 06:41:40.182 INFO - Received posted results 06:41:40.587 INFO - Killing Firefox... 06:41:40.651 INFO - Shutting down... 06:41:40.652 INFO - Stopping Acceptor ServerSocket[addr=0.0.0.0/0.0.0.0,port=0,localport=14440] + [ 0 != 0 ] + echo ### [INFO] suite-webengine-tags TEST SUCCESSFUL ### [INFO] suite-webengine-tags TEST SUCCESSFUL + sleep 5 + [ 9 != 0 ] + echo ### [ERROR] TESTS FAILURE ### [ERROR] TESTS FAILURE + exit 9 + ret1=9 + '[' -z ']' + java -version + grep 1.6.0 java version "1.6.0_24" Java(TM) SE Runtime Environment (build 1.6.0_24-b07) + '[' 0 == 0 ']' + cd <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/nuxeo-distribution-5.4/nuxeo-distribution-dm/ftest/funkload> + make EXT=--no-color mkdir -p ../../target/ftest/funkload/report ../../target/ftest/funkload/log fl-monitor-ctl monitor.conf restart Starting monitor server at http://localhost:55502/ as daemon. fl-credential-ctl credential.conf restart Starting file_credential server at http://localhost:55501/ as daemon. fl-run-test test_pages.py --simple-fetch -v -e testAvailable --no-color testAvailable (test_pages.Pages) ... ok ---------------------------------------------------------------------- Ran 1 test in 0.026s OK fl-run-test -v --simple-fetch test_rest.py -e testWriter --no-color testWriter (test_rest.Rest) ... ok ---------------------------------------------------------------------- Ran 1 test in 1.765s OK fl-run-test -v --simple-fetch test_rest.py -e testReader --no-color testReader (test_rest.Rest) ... ok ---------------------------------------------------------------------- Ran 1 test in 0.690s OK fl-run-test -v --simple-fetch test_pages.py --no-color testAvailable (test_pages.Pages) ... ok testBasePageViewDocumentPath (test_pages.Pages) ... ok testFileTabs (test_pages.Pages) ... ok testFolderPage (test_pages.Pages) ... ok testLoginPage (test_pages.Pages) ... ok testNavigation (test_pages.Pages) ... ok testPublish (test_pages.Pages) ... ok testSections (test_pages.Pages) ... ok testUsersGroupsPage (test_pages.Pages) ... ok ---------------------------------------------------------------------- Ran 9 tests in 40.895s OK fl-run-test -v --simple-fetch test_nuxeo.py -e testInit --no-color testInit (test_nuxeo.Nuxeo) ... ok ---------------------------------------------------------------------- Ran 1 test in 10.473s OK fl-run-test -v --simple-fetch test_nuxeo.py -e testWriter --no-color testWriter (test_nuxeo.Nuxeo) ... ok ---------------------------------------------------------------------- Ran 1 test in 12.576s OK fl-run-test -v --simple-fetch test_nuxeo.py -e testReader --no-color testReader (test_nuxeo.Nuxeo) ... ok ---------------------------------------------------------------------- Ran 1 test in 2.458s OK fl-run-test -v --simple-fetch test_webengine.py --no-color test_testWebengine (test_webengine.Webengine) ... ok ---------------------------------------------------------------------- Ran 1 test in 2.017s OK fl-run-test -v --simple-fetch test_dav.py --no-color testDav (test_dav.Dav) ... ok testLocks (test_dav.Dav) ... ok testUserAgents (test_dav.Dav) ... ok testWriter (test_dav.Dav) ... ok ---------------------------------------------------------------------- Ran 4 tests in 3.584s OK fl-monitor-ctl monitor.conf stop Server http://localhost:55502/ is stopped. fl-credential-ctl credential.conf stop Server http://localhost:55501/ is stopped. + ret2=0 + ret3=0 + stop_server + SERVER_HOME=<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss> + <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/bin/monitorctl.sh> stop <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/bin/monitorctl.sh>: line 121: -server -Xms1g -Xmx1g -XX:MaxPermSize=512m -Dsun.rmi.dgc.client.gcInterval=3600000 -Dsun.rmi.dgc.server.gcInterval=3600000 -Xloggc:${nuxeo.log.dir}/gc.log -verbose:gc -XX:+PrintGCDetails -XX:+PrintGCTimeStamps: bad substitution Stopping monitoring 2011-05-20 06:43:04+02:00 ... cat: <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/jmxstat.pid>: No such file or directory kill: usage: kill [-s sigspec | -n signum | -sigspec] pid | jobspec ... or kill -l [sigspec] Monitoring stopped. Archiving log ... Done: <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/log-20110520-044310.tgz> + <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/bin/nuxeoctl> stop Launcher command: java -Dlauncher.java.opts=-server -Xms1g -Xmx1g -XX:MaxPermSize=512m -Dsun.rmi.dgc.client.gcInterval=3600000 -Dsun.rmi.dgc.server.gcInterval=3600000 -Xloggc:${nuxeo.log.dir}/gc.log -verbose:gc -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -Djava.net.preferIPv4Stack=true -Djava.awt.headless=true -Dnuxeo.home=<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss> -Dnuxeo.conf=<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/bin/nuxeo.conf> -Dnuxeo.log.dir=<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/log> -jar <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/bin/nuxeo-launcher.jar> stop Nuxeo home: <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss> Nuxeo configuration: <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/bin/nuxeo.conf> May 20, 2011 6:43:10 AM org.artofsolving.jodconverter.util.PlatformUtils searchExistingfile INFO: Jod will be using /usr/lib/openoffice May 20, 2011 6:43:10 AM org.artofsolving.jodconverter.util.PlatformUtils searchExistingfile INFO: Jod will be using /home/hudson/.openoffice.org/3 Include template: <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/templates/common-deploydir> Include template: <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/templates/common-binding> Include template: <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/templates/common> Include template: <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/templates/oracle> Include template: <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/templates/monitor> Stopping server..................No answer from server, try to kill process 27129... + '[' '!' -z ']' + echo '### 10 most frequent errors --------------' ### 10 most frequent errors -------------- + grep ' ERROR \[' <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/server.log> + sed 's/^.\{24\}//g' + sort -nr + sort + head + uniq -c 12 ERROR [org.jboss.seam.jsf.SeamPhaseListener] swallowing exception 11 ERROR [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/nuxeo]] Session event listener threw exception 7 ERROR [nuxeo-error-log] 6 ERROR [org.nuxeo.ecm.core.event.impl.ReconnectedEventBundleImpl] Can not refetch Doc with ref e46ec649-e1b2-4e62-94ae-24e77d35e67a 6 ERROR [org.nuxeo.ecm.core.event.impl.ReconnectedEventBundleImpl] Can not refetch Doc with ref 1d375ba2-5caa-476a-8a12-01f450c06604 3 ERROR [org.nuxeo.ecm.platform.web.common.exceptionhandling.DefaultNuxeoExceptionHandler] javax.el.ELException: org.nuxeo.ecm.core.api.DocumentSecurityException: Privilege 'Read' is not granted to 'jdoe' 3 ERROR [org.nuxeo.ecm.core.storage.sql.jdbc.JDBCLogger] (4) SQL: Resetting connection 3 ERROR [nuxeo-error-log] javax.el.ELException: org.nuxeo.ecm.core.api.DocumentSecurityException: Privilege 'Read' is not granted to 'jdoe' 2 ERROR [org.nuxeo.ecm.core.storage.sql.jdbc.JDBCMapper] XA end error on < 131075, 29, 27, 49455510248484849484958981004857585210010053101985252584957505755102484848494849589810048575852100100531019852525849575099 > 2 ERROR [org.nuxeo.ecm.core.storage.sql.jdbc.JDBCMapper] XA end error on < 131075, 29, 27, 4945551024848484948495898100485758521001005310198525258495610257551024848484948495898100485758521001005310198525258495610299 > + echo '### 10 first errors ----------------------' ### 10 first errors ---------------------- + grep -nTm 10 ' ERROR ' <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/server.log> 1753 :2011-05-20 06:17:55,219 ERROR [org.nuxeo.runtime.model.impl.ComponentManagerImpl] Failed to create component: service:org.nuxeo.runtime.management.metrics 1927 :2011-05-20 06:18:10,797 ERROR [org.nuxeo.runtime.osgi.OSGiRuntimeService] Nuxeo EP Started 2032 :2011-05-20 06:22:52,913 ERROR [org.nuxeo.ecm.core.storage.sql.jdbc.JDBCLogger] (4) SQL: Resetting connection 2292 :2011-05-20 06:23:35,024 ERROR [org.nuxeo.ecm.core.storage.sql.jdbc.JDBCMapper] XA end error on < 131075, 29, 27, 494555102484848494849589810048575852100100531019852525849564898551024848484948495898100485758521001005310198525258495648101 > 2498 :2011-05-20 06:23:35,050 ERROR [org.jboss.seam.jsf.SeamPhaseListener] swallowing exception 2597 :2011-05-20 06:23:35,602 ERROR [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/nuxeo]] Session event listener threw exception 2644 :2011-05-20 06:23:43,620 ERROR [org.nuxeo.ecm.core.storage.sql.jdbc.JDBCLogger] (4) SQL: Resetting connection 2904 :2011-05-20 06:24:42,866 ERROR [org.nuxeo.ecm.core.storage.sql.jdbc.JDBCMapper] XA end error on < 131075, 29, 27, 4945551024848484948495898100485758521001005310198525258495610257551024848484948495898100485758521001005310198525258495610299 > 3016 :2011-05-20 06:24:42,876 ERROR [org.nuxeo.ecm.core.storage.sql.jdbc.JDBCMapper] XA end error on < 131075, 29, 27, 4945551024848484948495898100485758521001005310198525258495610257551024848484948495898100485758521001005310198525258495610299 > 3225 :2011-05-20 06:24:42,884 ERROR [org.jboss.seam.jsf.SeamPhaseListener] swallowing exception + echo '### --------------------------------------' ### -------------------------------------- + gzip <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/boot.log> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/console.log> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/gc.log> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/jvm.log> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/nuxeo-ds.log> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/nuxeo-error.log> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/server.log> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/stderr.log> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trun k/jboss/log/sysstat-sar.log> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/vcs-ds.log> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/webrequests.log> <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/webthreads.log> + gzip -cd <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/115/artifact/trunk/jboss/log/server.log.gz> + '[' 9 -eq 0 -a 0 -eq 0 ']' + exit 9 Archiving artifacts Publishing Selenium report... Test failures: 32 Test totals : 108 ------------------------ Command Passes : 989 Command Failures : 32 Command Errors : 30 _______________________________________________ ECM-QA mailing list [email protected] http://lists.nuxeo.com/mailman/listinfo/ecm-qa
