Hi,
UI tests seem to be failing rather consistently.
As far as I can understand, "Caused by:
org.openqa.selenium.WebDriverException: Unable to bind to locking port 7054
within 45000 ms" can have 2 causes:
1) A firefox instance is still opened when the test starts, so a new one
can not be opened for the actual test.
- This does not seem to be the case, as before and after the test I can see
no opened firefox process.
2) Incompatibility between the selenium version (2.40.0) and the firefox
version (27.0.1)? [1][2]
- I doubt this is the case, since we did not change anything here recently.
Any ideas? This seems to be reproducing on various agents both on master
and on the 6.1 branch (for example).
Thanks,
Eduard
----------
[1]
On Sun, Aug 24, 2014 at 12:11 AM, Jenkins <build.noreply(a)xwiki.org> wrote:
Check console output at
http://ci.xwiki.org/job/xwiki-enterprise-test-ui/3380/ to view the
results.
Changes since the previous successful build:
Changes for Build #3360
Changes for Build #3361
Changes for Build #3362
[Guillaume Delhumeau] Aug 18, 2014 12:24:23 PM XWIKI-10809: Move Font
Awesome Icon Theme in the Icon module.
(rev 77519cad877ea487b44e3fb1155a54df14c1dbe8)
[Guillaume Delhumeau] Aug 18, 2014 1:07:56 PM XWIKI-10809: Move Font
Awesome Icon Theme in the Icon module.
(rev d660fdd67064ae6ff0259b44cfe937b8597d8a8e)
Changes for Build #3363
[Guillaume Delhumeau] Aug 20, 2014 5:25:46 PM XE-1404: Switch to Flamingo
Skin by default.
(rev 7c40f89248189af3486e4be631f5af9e1b566217)
Changes for Build #3364
[Guillaume Delhumeau] Aug 20, 2014 5:30:43 PM XE-1404: Switch to Flamingo
Skin by default.
(rev 90f4d266e2bf70a383494066a8493f4ba0985101)
Changes for Build #3365
Changes for Build #3366
[Guillaume Delhumeau] Aug 20, 2014 5:49:43 PM XWIKI-10809: Move Font
Awesome Icon Theme in the Icon module.
(rev fb62b467cae9932774f56951adcc8a33d3dd36e2)
[Guillaume Delhumeau] Aug 20, 2014 6:22:10 PM XWIKI-10823: Rename the LESS
module artifacts.
(rev 56d184b40058fa529d794c9c04898d6b7f9f1686)
[Guillaume Delhumeau] Aug 20, 2014 6:52:16 PM XE-1404: Switch to Flamingo
Skin by default.
(rev 41d771917b8e076a92c3bd370783c42867e086e1)
[Guillaume Delhumeau] Aug 20, 2014 7:01:26 PM XE-1404: Switch to Flamingo
Skin by default.
(rev a481aeaa498763c11b88a290ba76abb373be1bc5)
Changes for Build #3367
Changes for Build #3368
[Guillaume Delhumeau] Aug 21, 2014 1:12:35 PM XE-1404: Switch to Flamingo
Skin by default.
(rev 65ede7701c8d2ad86238e1d17eca692f4646e368)
Changes for Build #3369
[Guillaume Delhumeau] Aug 21, 2014 1:58:27 PM XE-1404: Switch to Flamingo
Skin by default.
(rev 655d0f6b1869749207543b1c9abb5399deaf38e7)
Changes for Build #3370
Changes for Build #3371
Changes for Build #3372
Changes for Build #3373
[Guillaume Delhumeau] Aug 21, 2014 5:48:59 PM XE-1404: Switch to Flamingo
Skin by default.
(rev 61720c645a9cc960a7673611e1d3c01ca5346057)
Changes for Build #3374
Changes for Build #3375
[Vincent Massol] Aug 22, 2014 12:53:09 PM [Misc] Revert changes done by
sdumitriu. The relativePath should not be set to not cause problems with
Jenkins. BTW I couldn't reproduce the build warning problem that Sergiu
fixed in his commit (would be interested to know more about it).
(rev a74558130c4625fdd1dd6c3e2b5b609ed6c39b44)
Changes for Build #3376
Changes for Build #3377
[Vincent Massol] Aug 22, 2014 5:34:44 PM [Misc] Force Maven to use its
repositories to resolve parent POM for top level modules. This is to
prevent any error such as having a pom.xml file located in a directory
above the top level modules for example.
(rev 172194e2764218ba6796f6fb4e6bd3972ca2ce66)
[Vincent Massol] Aug 22, 2014 6:19:43 PM XWIKI-10828: Speed up XWiki
instance starting in functional tests
(rev 6b00f7cd764aaab4522d1be7b65fff2c481d7a6e)
[Eduard Moraru] Aug 23, 2014 12:15:44 AM XE-1404: Switch to Flamingo Skin
by default.
(rev b9260b50a3f69a99c96a050208e92f8d71688c91)
Changes for Build #3378
Changes for Build #3379
Changes for Build #3380
Failed tests:
1 tests failed.
FAILED:
org.apache.maven.surefire.junit4.JUnit4Provider.org.xwiki.test.ui.AllTests
Error Message:
Failed to initialize PersistentTestContext
Stack Trace:
java.lang.RuntimeException: Failed to initialize PersistentTestContext
at
org.xwiki.test.ui.PageObjectSuite.beforeTests(PageObjectSuite.java:67)
at
org.xwiki.test.integration.XWikiExecutorSuite.run(XWikiExecutorSuite.java:170)
at
org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264)
at
org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
at
org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124)
at
org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200)
at
org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153)
at
org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
Caused by: org.openqa.selenium.WebDriverException: Unable to bind to
locking port 7054 within 45000 ms
Build info: version: '2.40.0', revision:
'4c5c0568b004f67810ee41c459549aa4b09c651e', time: '2014-02-19 11:13:01'
System info: host: 'vagent-3-3-dev', ip: '127.0.0.1', os.name:
'Linux',
os.arch: 'amd64', os.version: '3.10.23-vs2.3.6.8-beng', java.version:
'1.7.0_55'
Driver info: driver.version: FirefoxDriver
at org.openqa.selenium.internal.SocketLock.lock(SocketLock.java:98)
at
org.openqa.selenium.firefox.internal.NewProfileExtensionConnection.start(NewProfileExtensionConnection.java:84)
at
org.openqa.selenium.firefox.FirefoxDriver.startClient(FirefoxDriver.java:247)
at
org.openqa.selenium.remote.RemoteWebDriver.<init>(RemoteWebDriver.java:112)
at
org.openqa.selenium.firefox.FirefoxDriver.<init>(FirefoxDriver.java:192)
at
org.openqa.selenium.firefox.FirefoxDriver.<init>(FirefoxDriver.java:187)
at
org.openqa.selenium.firefox.FirefoxDriver.<init>(FirefoxDriver.java:183)
at
org.openqa.selenium.firefox.FirefoxDriver.<init>(FirefoxDriver.java:100)
at
org.xwiki.test.ui.WebDriverFactory.createWebDriver(WebDriverFactory.java:56)
at
org.xwiki.test.ui.PersistentTestContext.<init>(PersistentTestContext.java:72)
at
org.xwiki.test.ui.PageObjectSuite.beforeTests(PageObjectSuite.java:59)
... 7 more
Last build logs:
[...truncated 393 lines...]
[WARNING]
/home/hudsonagent/hudson_root/workspace/xwiki-enterprise-test-ui/xwiki-enterprise-test/xwiki-enterprise-test-ui/src/test/it/org/xwiki/test/ui/invitation/InvitationTest.java:[255,22]
registerLoginAndGotoPage(java.lang.String,java.lang.String,java.lang.String)
in org.xwiki.test.ui.TestUtils has been deprecated
[WARNING]
/home/hudsonagent/hudson_root/workspace/xwiki-enterprise-test-ui/xwiki-enterprise-test/xwiki-enterprise-test-ui/src/test/it/org/xwiki/test/ui/invitation/InvitationTest.java:[310,22]
registerLoginAndGotoPage(java.lang.String,java.lang.String,java.lang.String)
in org.xwiki.test.ui.TestUtils has been deprecated
[WARNING]
/home/hudsonagent/hudson_root/workspace/xwiki-enterprise-test-ui/xwiki-enterprise-test/xwiki-enterprise-test-ui/src/test/it/org/xwiki/test/ui/invitation/InvitationTest.java:[688,22]
registerLoginAndGotoPage(java.lang.String,java.lang.String,java.lang.String)
in org.xwiki.test.ui.TestUtils has been deprecated
[INFO]
[INFO] --- maven-dependency-plugin:2.8:unpack (unzip-application) @
xwiki-enterprise-test-ui ---
[INFO] Configured Artifact:
org.xwiki.enterprise:xwiki-enterprise-jetty-hsqldb:6.2-SNAPSHOT:zip
Downloading:
http://nexus.xwiki.org/nexus/content/groups/public-snapshots/org/xwiki/ente…
Downloaded:
http://nexus.xwiki.org/nexus/content/groups/public-snapshots/org/xwiki/ente…
(799 B at 20.0 KB/sec)
Downloading:
http://nexus.xwiki.org/nexus/content/groups/public-snapshots/org/xwiki/ente…
Downloaded:
http://nexus.xwiki.org/nexus/content/groups/public-snapshots/org/xwiki/ente…
(220669 KB at 11465.1 KB/sec)
[INFO] Unpacking
/home/hudsonagent/hudson_root/workspace/xwiki-enterprise-test-ui/.repository/org/xwiki/enterprise/xwiki-enterprise-jetty-hsqldb/6.2-SNAPSHOT/xwiki-enterprise-jetty-hsqldb-6.2-SNAPSHOT.zip
to
/home/hudsonagent/hudson_root/workspace/xwiki-enterprise-test-ui/xwiki-enterprise-test/xwiki-enterprise-test-ui/target
with includes "" and excludes ""
[INFO]
[INFO] --- maven-surefire-plugin:2.16:test (default) @
xwiki-enterprise-test-ui ---
[INFO] Surefire report directory:
/home/hudsonagent/hudson_root/workspace/xwiki-enterprise-test-ui/xwiki-enterprise-test/xwiki-enterprise-test-ui/target/surefire-reports
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.xwiki.test.ui.AllTests
23:10:31.124 [main] INFO o.x.test.integration.XWikiExecutor - Starting
XWiki server at [
http://localhost:8080/xwiki/bin/get/Main/]
23:10:31.138 [main] INFO o.x.test.integration.XWikiExecutor - Checking
that XWiki is up and running...
23:10:31.143 [Thread-1] INFO o.x.t.i.XWikiLogOutputStream - Starting
Jetty on port 8080, please wait...
23:10:31.518 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - Error binding
monitor port 8079: java.net.BindException: Cannot assign requested address
23:10:38.638 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:38,634 [main] INFO o.x.s.s.i.EmbeddedSolrInstance - Starting
embedded Solr server...
23:10:38.640 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:38,638 [main] INFO o.x.s.s.i.EmbeddedSolrInstance - Using Solr home
directory: [data/solr]
23:10:39.931 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:39,930 [coreLoadExecutor-4-thread-1] WARN o.a.s.c.SolrCore
- [xwiki] Solr index directory
'/home/hudsonagent/hudson_root/workspace/xwiki-enterprise-test-ui/xwiki-enterprise-test/xwiki-enterprise-test-ui/target/xwiki-enterprise-jetty-hsqldb-6.2-SNAPSHOT/data/solr/xwiki/data/index'
doesn't exist. Creating new index...
23:10:40.351 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:40,351 [coreLoadExecutor-4-thread-1] WARN o.a.s.r.ManagedResource
- No stored data found for /rest/managed
23:10:40.352 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:40,351 [coreLoadExecutor-4-thread-1] WARN o.a.s.r.ManagedResource
- No registered observers for /rest/managed
23:10:40.498 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:40,498 [main] INFO o.x.s.s.i.EmbeddedSolrInstance - Started embedded
Solr server.
23:10:41.853 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:41.853:INFO:oxtjl.NotifyListener:----------------------------------
23:10:41.854 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:41.854:INFO:oxtjl.NotifyListener:Server started, you can now open
http://localhost:8080/ in your browser to access your wiki.
23:10:41.854 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:41.854:INFO:oxtjl.NotifyListener:----------------------------------
23:10:45.501 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:45,501 [XWiki initialization] INFO .HibernateDataMigrationManager -
Storage schema updates and data migrations are enabled
23:10:45.502 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:45,501 [XWiki initialization] INFO .HibernateDataMigrationManager -
No data migration to apply for wiki [xwiki] currently in version [54300]
23:10:45.502 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:45,502 [XWiki initialization] INFO .HibernateDataMigrationManager -
Checking Hibernate mapping and updating schema if needed for wiki [xwiki]
23:10:54.159 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:54,159 [XWiki Solr index job thread] INFO o.x.s.s.i.j.IndexerJob
- Starting job of type [solr.indexer] with identifier [[solr, indexer]]
23:10:54.966 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:54,965 [XWiki Solr index job thread] INFO o.x.s.s.i.j.IndexerJob
- 565 documents added, 0 deleted and 0 updated during the
synchronization of the Solr index.
23:10:54.966 [Thread-2] ERROR o.x.t.i.XWikiLogOutputStream - 2014-08-23
23:10:54,965 [XWiki Solr index job thread] INFO o.x.s.s.i.j.IndexerJob
- Finished job of type [solr.indexer] with identifier [[solr, indexer]]
23:10:56.985 [main] INFO o.x.test.integration.XWikiExecutor - Server is
answering to [
http://localhost:8080/xwiki/bin/get/Main/]... cool
Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 73.201 sec
<<< FAILURE! - in org.xwiki.test.ui.AllTests
org.xwiki.test.ui.AllTests Time elapsed: 73.2 sec <<< ERROR!
java.lang.RuntimeException: Failed to initialize PersistentTestContext
at
org.xwiki.test.ui.PageObjectSuite.beforeTests(PageObjectSuite.java:67)
at
org.xwiki.test.integration.XWikiExecutorSuite.run(XWikiExecutorSuite.java:170)
at
org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264)
at
org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
at
org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124)
at
org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200)
at
org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153)
at
org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
Caused by: org.openqa.selenium.WebDriverException: Unable to bind to
locking port 7054 within 45000 ms
Build info: version: '2.40.0', revision:
'4c5c0568b004f67810ee41c459549aa4b09c651e', time: '2014-02-19 11:13:01'
System info: host: 'vagent-3-3-dev', ip: '127.0.0.1', os.name:
'Linux',
os.arch: 'amd64', os.version: '3.10.23-vs2.3.6.8-beng', java.version:
'1.7.0_55'
Driver info: driver.version: FirefoxDriver
at org.openqa.selenium.internal.SocketLock.lock(SocketLock.java:98)
at
org.openqa.selenium.firefox.internal.NewProfileExtensionConnection.start(NewProfileExtensionConnection.java:84)
at
org.openqa.selenium.firefox.FirefoxDriver.startClient(FirefoxDriver.java:247)
at
org.openqa.selenium.remote.RemoteWebDriver.<init>(RemoteWebDriver.java:112)
at
org.openqa.selenium.firefox.FirefoxDriver.<init>(FirefoxDriver.java:192)
at
org.openqa.selenium.firefox.FirefoxDriver.<init>(FirefoxDriver.java:187)
at
org.openqa.selenium.firefox.FirefoxDriver.<init>(FirefoxDriver.java:183)
at
org.openqa.selenium.firefox.FirefoxDriver.<init>(FirefoxDriver.java:100)
at
org.xwiki.test.ui.WebDriverFactory.createWebDriver(WebDriverFactory.java:56)
at
org.xwiki.test.ui.PersistentTestContext.<init>(PersistentTestContext.java:72)
at
org.xwiki.test.ui.PageObjectSuite.beforeTests(PageObjectSuite.java:59)
... 7 more
Results :
Tests in error:
JUnit4Provider.invoke:124->executeTestSet:153->execute:264 » Runtime
Failed to...
Tests run: 1, Failures: 0, Errors: 1, Skipped: 0
[ERROR] There are test failures.
Please refer to
/home/hudsonagent/hudson_root/workspace/xwiki-enterprise-test-ui/xwiki-enterprise-test/xwiki-enterprise-test-ui/target/surefire-reports
for the individual test results.
[JENKINS] Recording test results
log4j:WARN No appenders could be found for logger
(org.apache.commons.beanutils.converters.BooleanConverter).
log4j:WARN Please initialize the log4j system properly.
[INFO]
[INFO] --- maven-install-plugin:2.5.1:install (default-install) @
xwiki-enterprise-test-ui ---
[INFO] Installing
/home/hudsonagent/hudson_root/workspace/xwiki-enterprise-test-ui/xwiki-enterprise-test/xwiki-enterprise-test-ui/pom.xml
to
/home/hudsonagent/hudson_root/workspace/xwiki-enterprise-test-ui/.repository/org/xwiki/enterprise/xwiki-enterprise-test-ui/6.2-SNAPSHOT/xwiki-enterprise-test-ui-6.2-SNAPSHOT.pom
[INFO]
[INFO] --- maven-deploy-plugin:2.8.1:deploy (default-deploy) @
xwiki-enterprise-test-ui ---
Downloading:
http://nexus.xwiki.org/nexus/content/repositories/snapshots/org/xwiki/enter…
Downloaded:
http://nexus.xwiki.org/nexus/content/repositories/snapshots/org/xwiki/enter…
(621 B at 28.9 KB/sec)
Uploading:
http://nexus.xwiki.org/nexus/content/repositories/snapshots/org/xwiki/enter…
Uploaded:
http://nexus.xwiki.org/nexus/content/repositories/snapshots/org/xwiki/enter…
(4 KB at 82.2 KB/sec)
Downloading:
http://nexus.xwiki.org/nexus/content/repositories/snapshots/org/xwiki/enter…
Downloaded:
http://nexus.xwiki.org/nexus/content/repositories/snapshots/org/xwiki/enter…
(932 B at 60.7 KB/sec)
Uploading:
http://nexus.xwiki.org/nexus/content/repositories/snapshots/org/xwiki/enter…
Uploaded:
http://nexus.xwiki.org/nexus/content/repositories/snapshots/org/xwiki/enter…
(621 B at 23.3 KB/sec)
Uploading:
http://nexus.xwiki.org/nexus/content/repositories/snapshots/org/xwiki/enter…
Uploaded:
http://nexus.xwiki.org/nexus/content/repositories/snapshots/org/xwiki/enter…
(932 B at 37.9 KB/sec)
[INFO]
------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO]
------------------------------------------------------------------------
[INFO] Total time: 1:56.610s
[INFO] Finished at: Sat Aug 23 23:11:47 CEST 2014
[INFO] Final Memory: 34M/489M
[INFO]
------------------------------------------------------------------------
[WARNING] The requested profile "legacy" could not be activated because it
does not exist.
[WARNING] The requested profile "jetty" could not be activated because it
does not exist.
Waiting for Jenkins to finish collecting data
[JENKINS] Archiving
/home/hudsonagent/hudson_root/workspace/xwiki-enterprise-test-ui/xwiki-enterprise-test/xwiki-enterprise-test-ui/pom.xml
to
org.xwiki.enterprise/xwiki-enterprise-test-ui/6.2-SNAPSHOT/xwiki-enterprise-test-ui-6.2-SNAPSHOT.pom
channel stopped
Terminating xvnc.
$ vncserver -kill :43
Killing Xtightvnc process ID 31217
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)