See <https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/>

------------------------------------------
[...truncated 691 lines...]
+ echo ### [ERROR] suite-dm TEST FAILURE
### [ERROR] suite-dm TEST FAILURE
+ exit_code=9
+ sleep 5
+ 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-5.4.3-I20110803_0115/nuxeo-distribution/nuxeo-distribution-dm/ftest/selenium/tests/suite-webengine.html>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/nuxeo-5.4.3-I20110803_0115/nuxeo-distribution/nuxeo-distribution-dm/ftest/selenium/results/result-suite-webengine.html>
 -firefoxProfileTemplate ffprofile -userExtensions user-extensions.js
07:26:03.774 INFO - Java: Sun Microsystems Inc. 19.1-b02
07:26:03.775 INFO - OS: Linux 2.6.32-30-generic-pae i386
07:26:03.781 INFO - v2.0 [b3], with Core v2.0 [b3]
07:26:03.885 INFO - RemoteWebDriver instances should connect to: 
http://127.0.0.1:14440/wd/hub
07:26:03.886 INFO - Version Jetty/5.1.x
07:26:03.887 INFO - Started 
HttpContext[/selenium-server/driver,/selenium-server/driver]
07:26:03.887 INFO - Started HttpContext[/selenium-server,/selenium-server]
07:26:03.888 INFO - Started HttpContext[/,/]
07:26:03.903 INFO - Started org.openqa.jetty.jetty.servlet.ServletHandler@a1d1f4
07:26:03.903 INFO - Started HttpContext[/wd,/wd]
07:26:03.907 INFO - Started SocketListener on 0.0.0.0:14440
07:26:03.907 INFO - Started org.openqa.jetty.jetty.Server@17471e0
07:26:04.007 INFO - Preparing Firefox profile...
07:26:06.253 INFO - Launching Firefox...
07:26:06.902 INFO - Checking Resource aliases
07:26:10.766 INFO - Checking Resource aliases
07:26:10.768 INFO - Received posted results
07:26:11.256 INFO - Killing Firefox...
07:26:11.317 INFO - Shutting down...
07:26:11.318 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-5.4.3-I20110803_0115/nuxeo-distribution/nuxeo-distribution-dm/ftest/selenium/tests/suite-webengine-website.html>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/nuxeo-5.4.3-I20110803_0115/nuxeo-distribution/nuxeo-distribution-dm/ftest/selenium/results/result-suite-webengine-website.html>
 -firefoxProfileTemplate ffprofile -userExtensions user-extensions.js
07:26:16.928 INFO - Java: Sun Microsystems Inc. 19.1-b02
07:26:16.930 INFO - OS: Linux 2.6.32-30-generic-pae i386
07:26:16.935 INFO - v2.0 [b3], with Core v2.0 [b3]
07:26:17.041 INFO - RemoteWebDriver instances should connect to: 
http://127.0.0.1:14440/wd/hub
07:26:17.042 INFO - Version Jetty/5.1.x
07:26:17.043 INFO - Started 
HttpContext[/selenium-server/driver,/selenium-server/driver]
07:26:17.044 INFO - Started HttpContext[/selenium-server,/selenium-server]
07:26:17.044 INFO - Started HttpContext[/,/]
07:26:42.575 INFO - Started 
org.openqa.jetty.jetty.servlet.ServletHandler@1be0f0a
07:26:42.576 INFO - Started HttpContext[/wd,/wd]
07:26:42.579 INFO - Started SocketListener on 0.0.0.0:14440
07:26:42.585 INFO - Started org.openqa.jetty.jetty.Server@17471e0
07:26:42.714 INFO - Preparing Firefox profile...
07:26:45.014 INFO - Launching Firefox...
07:26:45.643 INFO - Checking Resource aliases
07:27:40.942 INFO - Checking Resource aliases
07:27:40.943 INFO - Received posted results
07:27:41.532 INFO - Killing Firefox...
07:27:41.642 INFO - Shutting down...
+ [ 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-5.4.3-I20110803_0115/nuxeo-distribution/nuxeo-distribution-dm/ftest/selenium/tests/suite-webengine-tags.html>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/nuxeo-5.4.3-I20110803_0115/nuxeo-distribution/nuxeo-distribution-dm/ftest/selenium/results/result-suite-webengine-tags.html>
 -firefoxProfileTemplate ffprofile -userExtensions user-extensions.js
07:27:47.601 INFO - Java: Sun Microsystems Inc. 19.1-b02
07:27:47.602 INFO - OS: Linux 2.6.32-30-generic-pae i386
07:27:47.608 INFO - v2.0 [b3], with Core v2.0 [b3]
07:27:47.712 INFO - RemoteWebDriver instances should connect to: 
http://127.0.0.1:14440/wd/hub
07:27:47.713 INFO - Version Jetty/5.1.x
07:27:47.714 INFO - Started 
HttpContext[/selenium-server/driver,/selenium-server/driver]
07:27:47.714 INFO - Started HttpContext[/selenium-server,/selenium-server]
07:27:47.715 INFO - Started HttpContext[/,/]
07:27:47.730 INFO - Started 
org.openqa.jetty.jetty.servlet.ServletHandler@1df280b
07:27:47.730 INFO - Started HttpContext[/wd,/wd]
07:27:47.733 INFO - Started SocketListener on 0.0.0.0:14440
07:27:47.734 INFO - Started org.openqa.jetty.jetty.Server@1cdeff
07:27:47.823 INFO - Preparing Firefox profile...
07:27:50.112 INFO - Launching Firefox...
07:27:50.759 INFO - Checking Resource aliases
07:28:10.036 INFO - Checking Resource aliases
07:28:10.037 INFO - Received posted results
07:28:10.618 INFO - Killing Firefox...
Tests failed, see result file for details: 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/nuxeo-5.4.3-I20110803_0115/nuxeo-distribution/nuxeo-distribution-dm/ftest/selenium/results/result-suite-webengine-tags.html>
+ [ 1 != 0 ]
+ echo ### [ERROR] suite-webengine-tags TEST FAILURE
### [ERROR] suite-webengine-tags TEST FAILURE
+ exit_code=9
+ 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-5.4.3-I20110803_0115/nuxeo-distribution/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.013s

OK
fl-run-test -v --simple-fetch test_rest.py -e testWriter --no-color
testWriter (test_rest.Rest) ... ok

----------------------------------------------------------------------
Ran 1 test in 1.713s

OK
fl-run-test -v --simple-fetch test_rest.py -e testReader --no-color
testReader (test_rest.Rest) ... ok

----------------------------------------------------------------------
Ran 1 test in 0.910s

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 30.761s

OK
fl-run-test -v --simple-fetch test_nuxeo.py -e testInit --no-color
testInit (test_nuxeo.Nuxeo) ... ok

----------------------------------------------------------------------
Ran 1 test in 31.038s

OK
fl-run-test -v --simple-fetch test_nuxeo.py -e testWriter --no-color
testWriter (test_nuxeo.Nuxeo) ... ok

----------------------------------------------------------------------
Ran 1 test in 15.337s

OK
fl-run-test -v --simple-fetch test_nuxeo.py -e testReader --no-color
testReader (test_nuxeo.Nuxeo) ... ok

----------------------------------------------------------------------
Ran 1 test in 5.097s

OK
fl-run-test -v --simple-fetch test_webengine.py --no-color
test_testWebengine (test_webengine.Webengine) ... ok

----------------------------------------------------------------------
Ran 1 test in 2.123s

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 6.667s

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 -Dcom.sun.management.jmxremote 
-Dcom.sun.management.jmxremote.port=1089 
-Dcom.sun.management.jmxremote.ssl=false 
-Dcom.sun.management.jmxremote.authenticate=false: bad substitution
Stopping monitoring 2011-08-03 07:29:55+02:00 ...
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/ws/trunk/jboss/bin/monitorctl.sh>:
 line 414: kill: (31816) - No such process
Monitoring stopped.
Archiving log ...
Done: 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/log-20110803-053000.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 
-Dcom.sun.management.jmxremote -Dcom.sun.management.jmxremote.port=1089 
-Dcom.sun.management.jmxremote.ssl=false 
-Dcom.sun.management.jmxremote.authenticate=false 
-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>
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 
31475...
Server forcibly stopped.
+ '[' '!' -z ']'
+ echo '### 10 most frequent errors --------------'
### 10 most frequent errors --------------
+ sort -nr
+ sort
+ sed 's/^.\{24\}//g'
+ grep ' ERROR \[' 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/server.log>
+ head
+ uniq -c
     10 ERROR [org.jboss.seam.jsf.SeamPhaseListener] swallowing exception
      9 ERROR 
[org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/nuxeo]] 
Session event listener threw exception
      6 ERROR [org.nuxeo.ecm.core.event.impl.ReconnectedEventBundleImpl] Can 
not refetch Doc with ref f46bfd4a-b912-428b-a0de-4acd3ec7550a
      6 ERROR [org.nuxeo.ecm.core.event.impl.ReconnectedEventBundleImpl] Can 
not refetch Doc with ref db414e4a-c5d4-4462-9e2c-28ba3f4fcc8c
      6 ERROR [nuxeo-error-log] 
      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 [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, 28, 26, 
49455510248484849484958975798995852101515610055102485810298995510248484849484958975798995852101515610055102485810298102
 >
      1 ERROR [org.nuxeo.ecm.webapp.context.NavigationContextBean] 
<resetCurrentPath> documentManager not initialized
      1 ERROR 
[org.nuxeo.ecm.platform.web.common.exceptionhandling.service.DefaultRequestDumper]
 ERROR TRYING TO GET THIS REQUEST ATTRIBUTE VALUE: lockActions
+ 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/161/artifact/trunk/jboss/log/server.log>
1919    :2011-08-03 07:14:01,370 ERROR 
[org.nuxeo.ecm.core.storage.sql.jdbc.JDBCLogger] (5) SQL: Resetting connection
2179    :2011-08-03 07:14:08,507 ERROR 
[org.nuxeo.ecm.core.storage.sql.jdbc.JDBCMapper] XA end error on < 131075, 28, 
26, 
494555102484848494849589757989958521015156100551024858101100575510248484849484958975798995852101515610055102485810110099
 >
2385    :2011-08-03 07:14:08,528 ERROR [org.jboss.seam.jsf.SeamPhaseListener] 
swallowing exception
2486    :2011-08-03 07:14:20,178 ERROR 
[org.nuxeo.ecm.core.storage.sql.jdbc.JDBCLogger] (4) SQL: Resetting connection
2746    :2011-08-03 07:14:28,925 ERROR 
[org.nuxeo.ecm.core.storage.sql.jdbc.JDBCMapper] XA end error on < 131075, 28, 
26, 
49455510248484849484958975798995852101515610055102485810298995510248484849484958975798995852101515610055102485810298102
 >
2858    :2011-08-03 07:14:28,934 ERROR 
[org.nuxeo.ecm.core.storage.sql.jdbc.JDBCMapper] XA end error on < 131075, 28, 
26, 
49455510248484849484958975798995852101515610055102485810298995510248484849484958975798995852101515610055102485810298102
 >
3067    :2011-08-03 07:14:28,954 ERROR [org.jboss.seam.jsf.SeamPhaseListener] 
swallowing exception
3241    :2011-08-03 07:14:30,967 ERROR [org.jboss.seam.jsf.SeamPhaseListener] 
swallowing exception
3316    :2011-08-03 07:14:30,971 ERROR 
[org.nuxeo.ecm.platform.ui.web.rest.FancyNavigationHandler] error occured while 
appending params to request: 
3421    :2011-08-03 07:14:32,390 ERROR 
[org.nuxeo.ecm.webapp.context.NavigationContextBean] <resetCurrentPath> 
documentManager not initialized
+ echo '### --------------------------------------'
### --------------------------------------
+ gzip 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/boot.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/console.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/gc.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/jmxstat.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/jvm.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/nuxeoctl.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/nuxeo-ds.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/nuxeo-error.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/t
 runk/jboss/log/server.log> 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/stderr.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/sysstat-sar.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/vcs-ds.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/webrequests.log>
 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/webthreads.log>
+ gzip -cd 
<https://qa.nuxeo.org/jenkins/job/IT-nuxeo-5.4-tests-dm-jboss-oracle11/161/artifact/trunk/jboss/log/server.log.gz>
+ '[' 9 -eq 0 -a 0 -eq 0 ']'
+ exit 9
Build step 'Execute shell' marked build as failure
Archiving artifacts
Publishing Selenium report...
  Test failures: 58
  Test totals  : 107
------------------------
  Command Passes   : 633
  Command Failures : 49
  Command Errors   : 53

_______________________________________________
ECM-QA mailing list
[email protected]
http://lists.nuxeo.com/mailman/listinfo/ecm-qa

Reply via email to