Build failed in Jenkins: Platform-FunctionalTests #88

3 views

Skip to first unread message

Jenkins

unread,

May 16, 2016, 9:37:40 PM5/16/16

to motec...@googlegroups.com, pge...@soldevelo.com, mtok...@soldevelo.com, pzur...@soldevelo.com

See <http://ci.motechproject.org/job/Platform-FunctionalTests/88/>

------------------------------------------
[...truncated 21409 lines...]
May 17, 2016 1:32:16 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
May 17, 2016 1:32:16 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 13032 ms
2016-05-17 01:32:18,335 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-17 01:32:20,348 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-17 01:32:22,355 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-17 01:32:24,361 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-17 01:32:26,367 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-17 01:32:28,372 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-17 01:32:30,379 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-17 01:32:32,385 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
[INFO] Initializing Spring FrameworkServlet 'servlet_3'
2016-05-17 01:32:34,391 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
[INFO] Initializing Spring FrameworkServlet 'servlet_4'
[INFO] Initialized servlet for org.motechproject.motech-platform-dataservices [123]
2016-05-17 01:32:36,437 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
[INFO] Initializing Spring FrameworkServlet 'servlet_7'
[INFO] Initialized servlet for org.motechproject.motech-platform-email [23]
[INFO] Initializing Spring FrameworkServlet 'servlet_9'
[INFO] Initialized servlet for org.motechproject.motech-platform-server-bundle [72]
[INFO] Initializing Spring FrameworkServlet 'servlet_11'
[INFO] Initialized servlet for org.motechproject.motech-platform-dataservices-web [17]
2016-05-17 01:32:39,212 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
[INFO] Initializing Spring FrameworkServlet 'servlet_13'
[INFO] Initialized servlet for org.motechproject.motech-admin [75]
[INFO] Initializing Spring FrameworkServlet 'servlet_15'
[INFO] Initialized servlet for org.motechproject.commcare [112]
[INFO] Initializing Spring FrameworkServlet 'servlet_16'
[INFO] Initialized servlet for org.motechproject.motech-scheduler [107]
2016-05-17 01:33:42,324 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-17 01:33:42,615 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-17 01:33:42,696 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-17 01:33:44,120 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-17 01:34:44,460 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-17 01:34:46,473 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-17 01:34:46,502 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-17 01:34:46,537 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-17 01:34:46,600 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-17 01:34:46,683 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-17 01:34:47,257 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-17 01:34:47,773 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-17 01:34:48,014 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-17 01:34:48,113 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-17 01:34:48,880 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-17 01:35:49,220 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-17 01:35:51,247 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-17 01:35:51,296 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-17 01:35:51,355 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-17 01:35:51,454 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-17 01:35:51,613 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-17 01:35:52,767 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
Tests run: 2, Failures: 0, Errors: 2, Skipped: 0, Time elapsed: 224.965 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.DataServicesUIFT
editEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 66.027 sec <<< ERROR!
org.openqa.selenium.TimeoutException: Timed out after 60 seconds waiting for element to be clickable: By.id: modulelink_data-services
Build info: version: '2.39.0', revision: '14fa800511cc5d66d426e08b0b2ab926c7ed7398', time: '2013-12-16 13:18:38'
System info: host: '3d43be742432', ip: '172.17.0.15', os.name: 'Linux', os.arch: 'amd64', os.version: '3.13.0-29-generic', java.version: '1.8.0_60'
Driver info: driver.version: unknown
at sun.reflect.GeneratedConstructorAccessor9.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:193)
at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:145)
at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:554)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:307)
at org.openqa.selenium.remote.RemoteWebDriver.findElementById(RemoteWebDriver.java:348)
at org.openqa.selenium.By$ById.findElement(By.java:220)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:299)
at org.openqa.selenium.support.ui.ExpectedConditions.findElement(ExpectedConditions.java:730)
at org.openqa.selenium.support.ui.ExpectedConditions.access$000(ExpectedConditions.java:39)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:130)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:126)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:528)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:521)
at org.openqa.selenium.support.ui.FluentWait.until(FluentWait.java:208)
at org.motechproject.uitest.page.AbstractBasePage.clickWhenVisible(AbstractBasePage.java:228)
at org.motechproject.uitest.page.MotechPage.goToDataServices(MotechPage.java:25)
at org.motechproject.testing.uifunctionaltests.DataServicesUIFT.initialize(DataServicesUIFT.java:26)

newEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 65.495 sec <<< ERROR!
org.openqa.selenium.TimeoutException: Timed out after 60 seconds waiting for element to be clickable: By.id: modulelink_data-services
Build info: version: '2.39.0', revision: '14fa800511cc5d66d426e08b0b2ab926c7ed7398', time: '2013-12-16 13:18:38'
System info: host: '3d43be742432', ip: '172.17.0.15', os.name: 'Linux', os.arch: 'amd64', os.version: '3.13.0-29-generic', java.version: '1.8.0_60'
Driver info: driver.version: unknown
at sun.reflect.GeneratedConstructorAccessor9.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:193)
at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:145)
at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:554)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:307)
at org.openqa.selenium.remote.RemoteWebDriver.findElementById(RemoteWebDriver.java:348)
at org.openqa.selenium.By$ById.findElement(By.java:220)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:299)
at org.openqa.selenium.support.ui.ExpectedConditions.findElement(ExpectedConditions.java:730)
at org.openqa.selenium.support.ui.ExpectedConditions.access$000(ExpectedConditions.java:39)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:130)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:126)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:528)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:521)
at org.openqa.selenium.support.ui.FluentWait.until(FluentWait.java:208)
at org.motechproject.uitest.page.AbstractBasePage.clickWhenVisible(AbstractBasePage.java:228)
at org.motechproject.uitest.page.MotechPage.goToDataServices(MotechPage.java:25)
at org.motechproject.testing.uifunctionaltests.DataServicesUIFT.initialize(DataServicesUIFT.java:26)

Picked up JAVA_TOOL_OPTIONS: -Xmx1024m -XX:MaxPermSize=512m -Xms512m
Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=512m; support was removed in 8.0
Running org.motechproject.testing.uifunctionaltests.HelloWorldFT
2016-05-17 01:35:59,078 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 2437
Only local connections are allowed.
2016-05-17 01:37:03,344 DEBUG [org.motechproject.testing.uifunctionaltests.HelloWorldFT] Hello world!
2016-05-17 01:37:04,092 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-17 01:37:04,348 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-17 01:37:04,429 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 67.616 sec - in org.motechproject.testing.uifunctionaltests.HelloWorldFT
Picked up JAVA_TOOL_OPTIONS: -Xmx1024m -XX:MaxPermSize=512m -Xms512m
Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=512m; support was removed in 8.0
Running org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
2016-05-17 01:37:06,883 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 12089
Only local connections are allowed.
2016-05-17 01:38:09,794 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-17 01:38:10,041 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-17 01:38:10,116 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-17 01:38:11,395 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.linkText: REST API
2016-05-17 01:38:11,443 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.linkText: REST API
2016-05-17 01:38:11,518 DEBUG [org.motechproject.uitest.page.RestApiPage] Waiting until element is clickable: By.id: modulelink_rest-docs
2016-05-17 01:39:11,874 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-17 01:39:13,884 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-17 01:39:13,921 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-17 01:39:13,955 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-17 01:39:14,001 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-17 01:39:14,104 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saving screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
2016-05-17 01:39:14,667 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saved screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 128.718 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
shouldOpenRestApiDocumentation(org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT) Time elapsed: 65.623 sec <<< ERROR!
org.openqa.selenium.TimeoutException: Timed out after 60 seconds waiting for element to be clickable: By.id: modulelink_rest-docs
Build info: version: '2.39.0', revision: '14fa800511cc5d66d426e08b0b2ab926c7ed7398', time: '2013-12-16 13:18:38'
System info: host: '3d43be742432', ip: '172.17.0.15', os.name: 'Linux', os.arch: 'amd64', os.version: '3.13.0-29-generic', java.version: '1.8.0_60'
Driver info: driver.version: unknown
at sun.reflect.GeneratedConstructorAccessor9.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:193)
at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:145)
at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:554)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:307)
at org.openqa.selenium.remote.RemoteWebDriver.findElementById(RemoteWebDriver.java:348)
at org.openqa.selenium.By$ById.findElement(By.java:220)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:299)
at org.openqa.selenium.support.ui.ExpectedConditions.findElement(ExpectedConditions.java:730)
at org.openqa.selenium.support.ui.ExpectedConditions.access$000(ExpectedConditions.java:39)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:130)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:126)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:528)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:521)
at org.openqa.selenium.support.ui.FluentWait.until(FluentWait.java:208)
at org.motechproject.uitest.page.AbstractBasePage.clickWhenVisible(AbstractBasePage.java:228)
at org.motechproject.uitest.page.RestApiPage.openDataServicsApi(RestApiPage.java:21)
at org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT.shouldOpenRestApiDocumentation(RestApiDocumentationUIFT.java:31)

Results :

Tests in error:
DataServicesUIFT.initialize:26 ? Timeout Timed out after 60 seconds waiting fo...
DataServicesUIFT.initialize:26 ? Timeout Timed out after 60 seconds waiting fo...
RestApiDocumentationUIFT.shouldOpenRestApiDocumentation:31 ? Timeout Timed out...

Tests run: 4, Failures: 0, Errors: 3, Skipped: 0

[INFO]
[INFO] --- maven-t7-plugin:0.9.16:stop-forked (tomcat-stop) @ motech-ui-functional-tests ---
[INFO] running t7:stop-forked ...
May 17, 2016 1:39:15 AM org.apache.catalina.core.StandardServer await
INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.
May 17, 2016 1:39:15 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
May 17, 2016 1:39:15 AM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
[INFO] Destroying Spring FrameworkServlet 'servlet_1'
[INFO] Destroying Spring FrameworkServlet 'servlet_3'
May 17, 2016 1:39:16 AM org.apache.catalina.core.ApplicationContext log
INFO: Destroying Spring FrameworkServlet 'bootstrap-servlet'
May 17, 2016 1:39:16 AM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
May 17, 2016 1:39:16 AM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc
SEVERE: The web application [/motech-platform-server] registered the JDBC driver [com.mysql.jdbc.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
May 17, 2016 1:39:16 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixDispatchQueue] but has failed to stop it. This is very likely to create a memory leak.
May 17, 2016 1:39:16 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixStartLevel] but has failed to stop it. This is very likely to create a memory leak.
May 17, 2016 1:39:16 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [SCR Component Actor] but has failed to stop it. This is very likely to create a memory leak.
May 17, 2016 1:39:16 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Gemini Blueprint context shutdown thread] but has failed to stop it. This is very likely to create a memory leak.
May 17, 2016 1:39:16 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Gemini Blueprint context shutdown thread] but has failed to stop it. This is very likely to create a memory leak.
May 17, 2016 1:39:16 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Spring DM Context Creation Timer] but has failed to stop it. This is very likely to create a memory leak.
May 17, 2016 1:39:16 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Thread-67] but has failed to stop it. This is very likely to create a memory leak.
May 17, 2016 1:39:16 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixShutdown] but has failed to stop it. This is very likely to create a memory leak.
May 17, 2016 1:39:16 AM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/motech-platform-server] created a ThreadLocal with key of type [org.springframework.core.NamedInheritableThreadLocal] (value [Current invoker bundle context]) and a value of type [org.apache.felix.framework.BundleContextImpl] (value [org.apache.felix.framework.BundleContextImpl@1692db67]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 17, 2016 1:39:16 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8080"]
May 17, 2016 1:39:16 AM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8080"]
null
[INFO] Exit-Value 0
[INFO] Exit-Value ForkedTomcatShutdownHook 0
[INFO]
[INFO] --- maven-enforcer-plugin:1.4.1:enforce (enforce-no-package-cycles) @ motech-ui-functional-tests ---
[INFO] Directory <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/classes> could not be found.
[INFO] Adding directory <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/test-classes> for package cycles search.
[INFO]
[INFO] --- maven-failsafe-plugin:2.17:verify (integration-test) @ motech-ui-functional-tests ---
[INFO] Failsafe report directory: <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/failsafe-reports>
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] MOTECH UI Functional Testing ...................... SUCCESS [4.102s]
[INFO] MOTECH UI Test Framework .......................... SUCCESS [16.099s]
[INFO] MOTECH UI Functional Tests ........................ FAILURE [7:55.749s]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 8:18.624s
[INFO] Finished at: Tue May 17 01:39:19 UTC 2016
[INFO] Final Memory: 69M/718M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-failsafe-plugin:2.17:verify (integration-test) on project motech-ui-functional-tests: There are test failures.
[ERROR]
[ERROR] Please refer to <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/failsafe-reports> for the individual test results.
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :motech-ui-functional-tests
Build step 'Invoke top-level Maven targets' marked build as failure
Recording test results

Jenkins

unread,

May 17, 2016, 9:37:02 PM5/17/16

to motec...@googlegroups.com, pge...@soldevelo.com, mtok...@soldevelo.com, pzur...@soldevelo.com

See <http://ci.motechproject.org/job/Platform-FunctionalTests/89/>

------------------------------------------
[...truncated 699 lines...]
May 18, 2016 1:31:46 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/tomcat0/webapps/ROOT> has finished in 218 ms
May 18, 2016 1:31:46 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
May 18, 2016 1:31:46 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 14953 ms
2016-05-18 01:31:48,266 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-18 01:31:50,277 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-18 01:31:52,284 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-18 01:31:54,289 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-18 01:31:56,295 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-18 01:31:58,301 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-18 01:32:00,308 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-18 01:32:02,314 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_3'

2016-05-18 01:32:04,320 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_4'
[INFO] Initialized servlet for org.motechproject.motech-platform-dataservices [123]

2016-05-18 01:32:06,344 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_7'
[INFO] Initialized servlet for org.motechproject.motech-platform-email [23]
[INFO] Initializing Spring FrameworkServlet 'servlet_9'
[INFO] Initialized servlet for org.motechproject.motech-platform-server-bundle [72]
[INFO] Initializing Spring FrameworkServlet 'servlet_11'
[INFO] Initialized servlet for org.motechproject.motech-platform-dataservices-web [17]

2016-05-18 01:32:09,082 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_13'
[INFO] Initialized servlet for org.motechproject.motech-admin [75]
[INFO] Initializing Spring FrameworkServlet 'servlet_15'
[INFO] Initialized servlet for org.motechproject.commcare [112]
[INFO] Initializing Spring FrameworkServlet 'servlet_16'
[INFO] Initialized servlet for org.motechproject.motech-scheduler [107]

2016-05-18 01:33:12,151 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-18 01:33:12,427 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-18 01:33:12,507 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-18 01:33:14,000 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-18 01:34:14,327 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-18 01:34:16,336 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-18 01:34:16,365 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-18 01:34:16,397 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-18 01:34:16,442 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-18 01:34:16,542 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-18 01:34:17,122 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-18 01:34:17,655 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-18 01:34:17,882 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-18 01:34:17,949 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-18 01:34:18,889 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-18 01:35:19,314 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-18 01:35:21,338 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-18 01:35:21,366 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-18 01:35:21,398 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-18 01:35:21,451 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-18 01:35:21,534 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-18 01:35:22,108 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
Tests run: 2, Failures: 0, Errors: 2, Skipped: 0, Time elapsed: 227.554 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.DataServicesUIFT
editEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 66.029 sec <<< ERROR!

org.openqa.selenium.TimeoutException: Timed out after 60 seconds waiting for element to be clickable: By.id: modulelink_data-services
Build info: version: '2.39.0', revision: '14fa800511cc5d66d426e08b0b2ab926c7ed7398', time: '2013-12-16 13:18:38'
System info: host: '3d43be742432', ip: '172.17.0.15', os.name: 'Linux', os.arch: 'amd64', os.version: '3.13.0-29-generic', java.version: '1.8.0_60'
Driver info: driver.version: unknown
at sun.reflect.GeneratedConstructorAccessor9.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:193)
at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:145)
at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:554)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:307)
at org.openqa.selenium.remote.RemoteWebDriver.findElementById(RemoteWebDriver.java:348)
at org.openqa.selenium.By$ById.findElement(By.java:220)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:299)
at org.openqa.selenium.support.ui.ExpectedConditions.findElement(ExpectedConditions.java:730)
at org.openqa.selenium.support.ui.ExpectedConditions.access$000(ExpectedConditions.java:39)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:130)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:126)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:528)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:521)
at org.openqa.selenium.support.ui.FluentWait.until(FluentWait.java:208)
at org.motechproject.uitest.page.AbstractBasePage.clickWhenVisible(AbstractBasePage.java:228)
at org.motechproject.uitest.page.MotechPage.goToDataServices(MotechPage.java:25)
at org.motechproject.testing.uifunctionaltests.DataServicesUIFT.initialize(DataServicesUIFT.java:26)

newEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 64.974 sec <<< ERROR!

2016-05-18 01:35:23,732 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 2126

Only local connections are allowed.

2016-05-18 01:36:25,979 DEBUG [org.motechproject.testing.uifunctionaltests.HelloWorldFT] Hello world!
2016-05-18 01:36:26,737 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-18 01:36:27,006 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-18 01:36:27,084 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 65.697 sec - in org.motechproject.testing.uifunctionaltests.HelloWorldFT

Picked up JAVA_TOOL_OPTIONS: -Xmx1024m -XX:MaxPermSize=512m -Xms512m
Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=512m; support was removed in 8.0
Running org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT

2016-05-18 01:36:29,692 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 2572

Only local connections are allowed.

2016-05-18 01:37:32,532 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-18 01:37:32,803 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-18 01:37:32,903 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-18 01:37:34,301 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.linkText: REST API
2016-05-18 01:37:34,384 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.linkText: REST API
2016-05-18 01:37:34,492 DEBUG [org.motechproject.uitest.page.RestApiPage] Waiting until element is clickable: By.id: modulelink_rest-docs
2016-05-18 01:38:34,742 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-18 01:38:36,787 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-18 01:38:36,831 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-18 01:38:36,875 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-18 01:38:36,931 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-18 01:38:37,063 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saving screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
2016-05-18 01:38:37,759 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saved screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 129.018 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
shouldOpenRestApiDocumentation(org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT) Time elapsed: 66.345 sec <<< ERROR!

May 18, 2016 1:38:39 AM org.apache.catalina.core.StandardServer await

INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.

May 18, 2016 1:38:39 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
May 18, 2016 1:38:39 AM org.apache.catalina.core.StandardService stopInternal

INFO: Stopping service Catalina
[INFO] Destroying Spring FrameworkServlet 'servlet_1'
[INFO] Destroying Spring FrameworkServlet 'servlet_3'

May 18, 2016 1:38:39 AM org.apache.catalina.core.ApplicationContext log

INFO: Destroying Spring FrameworkServlet 'bootstrap-servlet'

May 18, 2016 1:38:39 AM org.apache.catalina.core.ApplicationContext log

INFO: Closing Spring root WebApplicationContext

May 18, 2016 1:38:39 AM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc

SEVERE: The web application [/motech-platform-server] registered the JDBC driver [com.mysql.jdbc.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.

May 18, 2016 1:38:39 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixDispatchQueue] but has failed to stop it. This is very likely to create a memory leak.

May 18, 2016 1:38:39 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixStartLevel] but has failed to stop it. This is very likely to create a memory leak.

May 18, 2016 1:38:39 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [SCR Component Actor] but has failed to stop it. This is very likely to create a memory leak.

May 18, 2016 1:38:39 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Gemini Blueprint context shutdown thread] but has failed to stop it. This is very likely to create a memory leak.

May 18, 2016 1:38:39 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Spring DM Context Creation Timer] but has failed to stop it. This is very likely to create a memory leak.

May 18, 2016 1:38:39 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Thread-65] but has failed to stop it. This is very likely to create a memory leak.
May 18, 2016 1:38:39 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixShutdown] but has failed to stop it. This is very likely to create a memory leak.

May 18, 2016 1:38:39 AM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/motech-platform-server] created a ThreadLocal with key of type [org.springframework.core.NamedInheritableThreadLocal] (value [Current invoker bundle context]) and a value of type [org.apache.felix.framework.BundleContextImpl] (value [org.apache.felix.framework.BundleContextImpl@6eb76935]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 18, 2016 1:38:39 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8080"]
May 18, 2016 1:38:39 AM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8080"]

[INFO] Exit-Value ForkedTomcatShutdownHook 0
[INFO]
[INFO] --- maven-enforcer-plugin:1.4.1:enforce (enforce-no-package-cycles) @ motech-ui-functional-tests ---
[INFO] Directory <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/classes> could not be found.
[INFO] Adding directory <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/test-classes> for package cycles search.
[INFO]
[INFO] --- maven-failsafe-plugin:2.17:verify (integration-test) @ motech-ui-functional-tests ---
[INFO] Failsafe report directory: <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/failsafe-reports>
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]

[INFO] MOTECH UI Functional Testing ...................... SUCCESS [4.082s]
[INFO] MOTECH UI Test Framework .......................... SUCCESS [11.957s]
[INFO] MOTECH UI Functional Tests ........................ FAILURE [7:24.193s]

[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------

[INFO] Total time: 7:41.413s
[INFO] Finished at: Wed May 18 01:38:42 UTC 2016
null
[INFO] Exit-Value 0
[INFO] Final Memory: 61M/644M

Jenkins

unread,

May 18, 2016, 9:37:26 PM5/18/16

to motec...@googlegroups.com, pge...@soldevelo.com, mtok...@soldevelo.com, pzur...@soldevelo.com

See <http://ci.motechproject.org/job/Platform-FunctionalTests/90/>

------------------------------------------
[...truncated 21675 lines...]
INFO: Deployment of web application directory <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/tomcat0/webapps/ROOT> has finished in 188 ms
May 19, 2016 1:32:07 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
May 19, 2016 1:32:07 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 14400 ms
2016-05-19 01:32:10,026 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-19 01:32:12,036 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-19 01:32:14,043 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-19 01:32:16,048 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-19 01:32:18,055 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-19 01:32:20,060 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-19 01:32:22,066 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_3'

2016-05-19 01:32:24,073 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-19 01:32:26,079 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_4'
[INFO] Initialized servlet for org.motechproject.motech-platform-dataservices [123]

2016-05-19 01:32:28,106 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

2016-05-19 01:32:30,859 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-19 01:32:32,866 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_13'

[INFO] Initialized servlet for org.motechproject.commcare [112]

[INFO] Initializing Spring FrameworkServlet 'servlet_14'

[INFO] Initialized servlet for org.motechproject.motech-admin [75]
[INFO] Initializing Spring FrameworkServlet 'servlet_15'

[INFO] Initialized servlet for org.motechproject.motech-scheduler [107]
2016-05-19 01:33:35,810 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-19 01:33:36,056 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-19 01:33:36,131 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-19 01:33:37,567 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-19 01:34:37,877 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-19 01:34:39,939 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-19 01:34:39,970 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-19 01:34:40,005 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-19 01:34:40,071 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-19 01:34:40,153 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-19 01:34:40,728 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-19 01:34:41,238 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-19 01:34:41,470 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-19 01:34:41,557 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-19 01:34:42,393 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-19 01:35:42,701 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-19 01:35:44,731 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-19 01:35:45,397 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-19 01:35:45,453 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-19 01:35:45,576 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-19 01:35:45,738 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-19 01:35:46,752 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
Tests run: 2, Failures: 0, Errors: 2, Skipped: 0, Time elapsed: 228.408 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.DataServicesUIFT
editEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 65.851 sec <<< ERROR!

System info: host: '9c18a436bea2', ip: '172.17.0.19', os.name: 'Linux', os.arch: 'amd64', os.version: '3.13.0-29-generic', java.version: '1.8.0_60'

Driver info: driver.version: unknown
at sun.reflect.GeneratedConstructorAccessor9.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:193)
at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:145)
at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:554)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:307)
at org.openqa.selenium.remote.RemoteWebDriver.findElementById(RemoteWebDriver.java:348)
at org.openqa.selenium.By$ById.findElement(By.java:220)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:299)
at org.openqa.selenium.support.ui.ExpectedConditions.findElement(ExpectedConditions.java:730)
at org.openqa.selenium.support.ui.ExpectedConditions.access$000(ExpectedConditions.java:39)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:130)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:126)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:528)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:521)
at org.openqa.selenium.support.ui.FluentWait.until(FluentWait.java:208)
at org.motechproject.uitest.page.AbstractBasePage.clickWhenVisible(AbstractBasePage.java:228)
at org.motechproject.uitest.page.MotechPage.goToDataServices(MotechPage.java:25)
at org.motechproject.testing.uifunctionaltests.DataServicesUIFT.initialize(DataServicesUIFT.java:26)

newEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 66.018 sec <<< ERROR!

System info: host: '9c18a436bea2', ip: '172.17.0.19', os.name: 'Linux', os.arch: 'amd64', os.version: '3.13.0-29-generic', java.version: '1.8.0_60'

2016-05-19 01:35:48,586 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 28399

Only local connections are allowed.

2016-05-19 01:36:51,725 DEBUG [org.motechproject.testing.uifunctionaltests.HelloWorldFT] Hello world!
2016-05-19 01:36:52,473 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-19 01:36:52,731 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-19 01:36:52,808 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 66.528 sec - in org.motechproject.testing.uifunctionaltests.HelloWorldFT

2016-05-19 01:36:55,333 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 6127

Only local connections are allowed.

2016-05-19 01:37:58,180 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-19 01:37:58,431 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-19 01:37:58,510 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-19 01:38:00,036 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.linkText: REST API
2016-05-19 01:38:00,101 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.linkText: REST API
2016-05-19 01:38:00,194 DEBUG [org.motechproject.uitest.page.RestApiPage] Waiting until element is clickable: By.id: modulelink_rest-docs
2016-05-19 01:39:02,646 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-19 01:39:04,689 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-19 01:39:04,727 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-19 01:39:04,763 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-19 01:39:04,812 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-19 01:39:04,916 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saving screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
2016-05-19 01:39:05,720 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saved screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 131.39 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
shouldOpenRestApiDocumentation(org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT) Time elapsed: 68.295 sec <<< ERROR!

org.openqa.selenium.TimeoutException: Timed out after 60 seconds waiting for element to be clickable: By.id: modulelink_rest-docs
Build info: version: '2.39.0', revision: '14fa800511cc5d66d426e08b0b2ab926c7ed7398', time: '2013-12-16 13:18:38'

System info: host: '9c18a436bea2', ip: '172.17.0.19', os.name: 'Linux', os.arch: 'amd64', os.version: '3.13.0-29-generic', java.version: '1.8.0_60'

May 19, 2016 1:39:06 AM org.apache.catalina.core.StandardServer await

INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.

May 19, 2016 1:39:06 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
May 19, 2016 1:39:06 AM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
[INFO] Destroying Spring FrameworkServlet 'servlet_3'

[INFO] Destroying Spring FrameworkServlet 'servlet_1'

May 19, 2016 1:39:06 AM org.apache.catalina.core.ApplicationContext log

INFO: Destroying Spring FrameworkServlet 'bootstrap-servlet'

May 19, 2016 1:39:07 AM org.apache.catalina.core.ApplicationContext log

INFO: Closing Spring root WebApplicationContext

May 19, 2016 1:39:07 AM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc

May 19, 2016 1:39:07 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixDispatchQueue] but has failed to stop it. This is very likely to create a memory leak.

May 19, 2016 1:39:07 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixStartLevel] but has failed to stop it. This is very likely to create a memory leak.

May 19, 2016 1:39:07 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [SCR Component Actor] but has failed to stop it. This is very likely to create a memory leak.

May 19, 2016 1:39:07 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Spring DM Context Creation Timer] but has failed to stop it. This is very likely to create a memory leak.

May 19, 2016 1:39:07 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Thread-66] but has failed to stop it. This is very likely to create a memory leak.
May 19, 2016 1:39:07 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixShutdown] but has failed to stop it. This is very likely to create a memory leak.

May 19, 2016 1:39:07 AM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/motech-platform-server] created a ThreadLocal with key of type [org.springframework.core.NamedInheritableThreadLocal] (value [Current invoker bundle context]) and a value of type [org.apache.felix.framework.BundleContextImpl] (value [org.apache.felix.framework.BundleContextImpl@49aeda1e]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 19, 2016 1:39:07 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8080"]
May 19, 2016 1:39:07 AM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-8080"]
null
[INFO] Exit-Value 0

[INFO] MOTECH UI Functional Testing ...................... SUCCESS [4.440s]
[INFO] MOTECH UI Test Framework .......................... SUCCESS [13.638s]
[INFO] MOTECH UI Functional Tests ........................ FAILURE [7:47.834s]

[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------

[INFO] Total time: 8:07.914s
[INFO] Finished at: Thu May 19 01:39:09 UTC 2016
[INFO] Final Memory: 65M/638M

Jenkins

unread,

May 19, 2016, 9:37:00 PM5/19/16

to motec...@googlegroups.com, pge...@soldevelo.com, mtok...@soldevelo.com, pzur...@soldevelo.com

See <http://ci.motechproject.org/job/Platform-FunctionalTests/91/>

------------------------------------------
[...truncated 700 lines...]
May 20, 2016 1:31:48 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/tomcat0/webapps/ROOT> has finished in 177 ms
May 20, 2016 1:31:48 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
May 20, 2016 1:31:48 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 14055 ms
2016-05-20 01:31:50,938 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-20 01:31:52,954 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-20 01:31:54,963 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-20 01:31:56,967 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-20 01:31:58,973 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-20 01:32:00,978 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-20 01:32:02,986 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_3'

2016-05-20 01:32:04,992 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-20 01:32:06,998 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_4'
[INFO] Initialized servlet for org.motechproject.motech-platform-dataservices [123]

2016-05-20 01:32:09,004 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

2016-05-20 01:32:11,798 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_13'

[INFO] Initialized servlet for org.motechproject.motech-admin [75]
[INFO] Initializing Spring FrameworkServlet 'servlet_15'

[INFO] Initialized servlet for org.motechproject.commcare [112]

[INFO] Initializing Spring FrameworkServlet 'servlet_16'

[INFO] Initialized servlet for org.motechproject.motech-scheduler [107]

2016-05-20 01:33:14,774 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-20 01:33:15,048 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-20 01:33:15,122 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-20 01:33:16,591 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-20 01:34:16,936 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-20 01:34:18,996 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-20 01:34:19,025 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-20 01:34:19,058 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-20 01:34:19,102 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-20 01:34:19,188 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-20 01:34:19,763 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-20 01:34:20,280 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-20 01:34:20,544 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-20 01:34:20,612 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-20 01:34:21,503 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-20 01:35:21,727 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-20 01:35:23,737 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-20 01:35:23,786 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-20 01:35:23,840 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-20 01:35:23,950 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-20 01:35:24,106 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-20 01:35:25,019 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
Tests run: 2, Failures: 0, Errors: 2, Skipped: 0, Time elapsed: 226.359 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.DataServicesUIFT
editEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 65.95 sec <<< ERROR!

org.openqa.selenium.TimeoutException: Timed out after 60 seconds waiting for element to be clickable: By.id: modulelink_data-services
Build info: version: '2.39.0', revision: '14fa800511cc5d66d426e08b0b2ab926c7ed7398', time: '2013-12-16 13:18:38'
System info: host: '9c18a436bea2', ip: '172.17.0.19', os.name: 'Linux', os.arch: 'amd64', os.version: '3.13.0-29-generic', java.version: '1.8.0_60'
Driver info: driver.version: unknown
at sun.reflect.GeneratedConstructorAccessor9.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:193)
at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:145)
at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:554)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:307)
at org.openqa.selenium.remote.RemoteWebDriver.findElementById(RemoteWebDriver.java:348)
at org.openqa.selenium.By$ById.findElement(By.java:220)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:299)
at org.openqa.selenium.support.ui.ExpectedConditions.findElement(ExpectedConditions.java:730)
at org.openqa.selenium.support.ui.ExpectedConditions.access$000(ExpectedConditions.java:39)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:130)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:126)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:528)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:521)
at org.openqa.selenium.support.ui.FluentWait.until(FluentWait.java:208)
at org.motechproject.uitest.page.AbstractBasePage.clickWhenVisible(AbstractBasePage.java:228)
at org.motechproject.uitest.page.MotechPage.goToDataServices(MotechPage.java:25)
at org.motechproject.testing.uifunctionaltests.DataServicesUIFT.initialize(DataServicesUIFT.java:26)

newEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 65.245 sec <<< ERROR!

2016-05-20 01:35:27,187 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 25832

Only local connections are allowed.

2016-05-20 01:36:30,627 DEBUG [org.motechproject.testing.uifunctionaltests.HelloWorldFT] Hello world!
2016-05-20 01:36:31,372 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-20 01:36:31,634 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-20 01:36:31,711 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 67.044 sec - in org.motechproject.testing.uifunctionaltests.HelloWorldFT

2016-05-20 01:36:34,186 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 17084

Only local connections are allowed.

2016-05-20 01:37:36,713 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-20 01:37:36,975 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-20 01:37:37,078 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-20 01:37:38,321 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.linkText: REST API
2016-05-20 01:37:38,371 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.linkText: REST API
2016-05-20 01:37:38,443 DEBUG [org.motechproject.uitest.page.RestApiPage] Waiting until element is clickable: By.id: modulelink_rest-docs
2016-05-20 01:38:38,599 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-20 01:38:40,639 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-20 01:38:40,683 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-20 01:38:40,727 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-20 01:38:40,780 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-20 01:38:40,875 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saving screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
2016-05-20 01:38:41,648 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saved screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 128.437 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
shouldOpenRestApiDocumentation(org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT) Time elapsed: 65.692 sec <<< ERROR!

May 20, 2016 1:38:42 AM org.apache.catalina.core.StandardServer await

INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.

May 20, 2016 1:38:42 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
May 20, 2016 1:38:42 AM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
[INFO] Destroying Spring FrameworkServlet 'servlet_1'

[INFO] Destroying Spring FrameworkServlet 'servlet_3'

May 20, 2016 1:38:42 AM org.apache.catalina.core.ApplicationContext log

INFO: Destroying Spring FrameworkServlet 'bootstrap-servlet'

May 20, 2016 1:38:43 AM org.apache.catalina.core.ApplicationContext log

INFO: Closing Spring root WebApplicationContext

May 20, 2016 1:38:43 AM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc

May 20, 2016 1:38:43 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixDispatchQueue] but has failed to stop it. This is very likely to create a memory leak.

May 20, 2016 1:38:43 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixStartLevel] but has failed to stop it. This is very likely to create a memory leak.

May 20, 2016 1:38:43 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [SCR Component Actor] but has failed to stop it. This is very likely to create a memory leak.

May 20, 2016 1:38:43 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Spring DM Context Creation Timer] but has failed to stop it. This is very likely to create a memory leak.

May 20, 2016 1:38:43 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Thread-74] but has failed to stop it. This is very likely to create a memory leak.
May 20, 2016 1:38:43 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixShutdown] but has failed to stop it. This is very likely to create a memory leak.

May 20, 2016 1:38:43 AM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/motech-platform-server] created a ThreadLocal with key of type [org.springframework.core.NamedInheritableThreadLocal] (value [Current invoker bundle context]) and a value of type [org.apache.felix.framework.BundleContextImpl] (value [org.apache.felix.framework.BundleContextImpl@2bf4f8d]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 20, 2016 1:38:43 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8080"]
May 20, 2016 1:38:43 AM org.apache.coyote.AbstractProtocol destroy

INFO: Destroying ProtocolHandler ["http-bio-8080"]
null
[INFO] Exit-Value 0
[INFO] Exit-Value ForkedTomcatShutdownHook 0
[INFO]
[INFO] --- maven-enforcer-plugin:1.4.1:enforce (enforce-no-package-cycles) @ motech-ui-functional-tests ---
[INFO] Directory <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/classes> could not be found.
[INFO] Adding directory <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/test-classes> for package cycles search.
[INFO]
[INFO] --- maven-failsafe-plugin:2.17:verify (integration-test) @ motech-ui-functional-tests ---
[INFO] Failsafe report directory: <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/failsafe-reports>
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]

[INFO] MOTECH UI Functional Testing ...................... SUCCESS [4.353s]
[INFO] MOTECH UI Test Framework .......................... SUCCESS [12.445s]
[INFO] MOTECH UI Functional Tests ........................ FAILURE [7:24.814s]

[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------

[INFO] Total time: 7:43.060s
[INFO] Finished at: Fri May 20 01:38:45 UTC 2016
[INFO] Final Memory: 65M/642M

Jenkins

unread,

May 20, 2016, 9:37:25 PM5/20/16

to motec...@googlegroups.com, pge...@soldevelo.com, mtok...@soldevelo.com, pzur...@soldevelo.com

See <http://ci.motechproject.org/job/Platform-FunctionalTests/92/>

------------------------------------------
[...truncated 22194 lines...]

[INFO] Initialized servlet for org.motechproject.motech-platform-dataservices-web [17]

2016-05-21 01:32:34,077 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-21 01:32:36,083 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_13'
[INFO] Initialized servlet for org.motechproject.motech-admin [75]
[INFO] Initializing Spring FrameworkServlet 'servlet_15'

[INFO] Initialized servlet for org.motechproject.motech-scheduler [107]

[INFO] Initializing Spring FrameworkServlet 'servlet_16'

[INFO] Initialized servlet for org.motechproject.commcare [112]
2016-05-21 01:33:39,368 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-21 01:33:39,624 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-21 01:33:39,697 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-21 01:33:41,221 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-21 01:34:41,595 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-21 01:34:43,654 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-21 01:34:43,684 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-21 01:34:43,716 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-21 01:34:43,782 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-21 01:34:43,867 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-21 01:34:44,471 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-21 01:34:45,024 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-21 01:34:45,292 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-21 01:34:45,364 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-21 01:34:46,314 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-21 01:35:46,636 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-21 01:35:48,654 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-21 01:35:48,692 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-21 01:35:48,732 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-21 01:35:48,814 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-21 01:35:48,978 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-21 01:35:49,911 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
Tests run: 2, Failures: 0, Errors: 2, Skipped: 0, Time elapsed: 227.124 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.DataServicesUIFT
newEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 66.121 sec <<< ERROR!

org.openqa.selenium.TimeoutException: Timed out after 60 seconds waiting for element to be clickable: By.id: modulelink_data-services
Build info: version: '2.39.0', revision: '14fa800511cc5d66d426e08b0b2ab926c7ed7398', time: '2013-12-16 13:18:38'
System info: host: '9c18a436bea2', ip: '172.17.0.19', os.name: 'Linux', os.arch: 'amd64', os.version: '3.13.0-29-generic', java.version: '1.8.0_60'
Driver info: driver.version: unknown
at sun.reflect.GeneratedConstructorAccessor9.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:193)
at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:145)
at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:554)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:307)
at org.openqa.selenium.remote.RemoteWebDriver.findElementById(RemoteWebDriver.java:348)
at org.openqa.selenium.By$ById.findElement(By.java:220)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:299)
at org.openqa.selenium.support.ui.ExpectedConditions.findElement(ExpectedConditions.java:730)
at org.openqa.selenium.support.ui.ExpectedConditions.access$000(ExpectedConditions.java:39)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:130)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:126)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:528)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:521)
at org.openqa.selenium.support.ui.FluentWait.until(FluentWait.java:208)
at org.motechproject.uitest.page.AbstractBasePage.clickWhenVisible(AbstractBasePage.java:228)
at org.motechproject.uitest.page.MotechPage.goToDataServices(MotechPage.java:25)
at org.motechproject.testing.uifunctionaltests.DataServicesUIFT.initialize(DataServicesUIFT.java:26)

editEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 65.428 sec <<< ERROR!

2016-05-21 01:35:51,833 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 20737

Only local connections are allowed.

2016-05-21 01:36:54,856 DEBUG [org.motechproject.testing.uifunctionaltests.HelloWorldFT] Hello world!
2016-05-21 01:36:55,696 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-21 01:36:55,964 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-21 01:36:56,038 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 66.799 sec - in org.motechproject.testing.uifunctionaltests.HelloWorldFT

2016-05-21 01:36:58,613 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 27062

Only local connections are allowed.

2016-05-21 01:38:01,797 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-21 01:38:02,086 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-21 01:38:02,161 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-21 01:38:03,435 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.linkText: REST API
2016-05-21 01:38:03,481 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.linkText: REST API
2016-05-21 01:38:03,552 DEBUG [org.motechproject.uitest.page.RestApiPage] Waiting until element is clickable: By.id: modulelink_rest-docs
2016-05-21 01:39:06,033 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-21 01:39:08,090 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-21 01:39:08,127 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-21 01:39:08,163 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-21 01:39:08,209 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-21 01:39:08,329 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saving screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
2016-05-21 01:39:09,175 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saved screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 131.489 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
shouldOpenRestApiDocumentation(org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT) Time elapsed: 68.266 sec <<< ERROR!

May 21, 2016 1:39:10 AM org.apache.catalina.core.StandardServer await

INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.

May 21, 2016 1:39:10 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
May 21, 2016 1:39:10 AM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
[INFO] Destroying Spring FrameworkServlet 'servlet_3'

[INFO] Destroying Spring FrameworkServlet 'servlet_1'

May 21, 2016 1:39:10 AM org.apache.catalina.core.ApplicationContext log

INFO: Destroying Spring FrameworkServlet 'bootstrap-servlet'

May 21, 2016 1:39:10 AM org.apache.catalina.core.ApplicationContext log

INFO: Closing Spring root WebApplicationContext

May 21, 2016 1:39:10 AM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc

May 21, 2016 1:39:10 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixDispatchQueue] but has failed to stop it. This is very likely to create a memory leak.

May 21, 2016 1:39:10 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixStartLevel] but has failed to stop it. This is very likely to create a memory leak.

May 21, 2016 1:39:10 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [SCR Component Actor] but has failed to stop it. This is very likely to create a memory leak.

May 21, 2016 1:39:10 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Spring DM Context Creation Timer] but has failed to stop it. This is very likely to create a memory leak.

May 21, 2016 1:39:10 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/motech-platform-server] appears to have started a thread named [Thread-70] but has failed to stop it. This is very likely to create a memory leak.
May 21, 2016 1:39:10 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads

SEVERE: The web application [/motech-platform-server] appears to have started a thread named [FelixShutdown] but has failed to stop it. This is very likely to create a memory leak.

May 21, 2016 1:39:10 AM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/motech-platform-server] created a ThreadLocal with key of type [org.springframework.core.NamedInheritableThreadLocal] (value [Current invoker bundle context]) and a value of type [org.apache.felix.framework.BundleContextImpl] (value [org.apache.felix.framework.BundleContextImpl@726c3871]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
May 21, 2016 1:39:10 AM org.apache.catalina.loader.WebappClassLoader loadedByThisOrChild
WARNING: webappClassLoader
java.util.ConcurrentModificationException
at java.util.HashMap$HashIterator.nextNode(HashMap.java:1429)
at java.util.HashMap$KeyIterator.next(HashMap.java:1453)
at org.apache.catalina.loader.WebappClassLoader.loadedByThisOrChild(WebappClassLoader.java:2639)
at org.apache.catalina.loader.WebappClassLoader.checkThreadLocalMapForLeaks(WebappClassLoader.java:2554)
at org.apache.catalina.loader.WebappClassLoader.checkThreadLocalsForLeaks(WebappClassLoader.java:2509)
at org.apache.catalina.loader.WebappClassLoader.clearReferences(WebappClassLoader.java:2015)
at org.apache.catalina.loader.WebappClassLoader.stop(WebappClassLoader.java:1910)
at org.apache.catalina.loader.WebappLoader.stopInternal(WebappLoader.java:661)
at org.apache.catalina.util.LifecycleBase.stop(LifecycleBase.java:232)
at org.apache.catalina.core.StandardContext.stopInternal(StandardContext.java:5769)
at org.apache.catalina.util.LifecycleBase.stop(LifecycleBase.java:232)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1590)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1579)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)

May 21, 2016 1:39:10 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8080"]
May 21, 2016 1:39:10 AM org.apache.coyote.AbstractProtocol destroy

[INFO] MOTECH UI Functional Testing ...................... SUCCESS [4.319s]
[INFO] MOTECH UI Test Framework .......................... SUCCESS [14.183s]
[INFO] MOTECH UI Functional Tests ........................ FAILURE [7:49.518s]

[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------

[INFO] Total time: 8:09.908s
[INFO] Finished at: Sat May 21 01:39:13 UTC 2016
[INFO] Final Memory: 65M/657M

Jenkins

unread,

May 21, 2016, 9:37:00 PM5/21/16

to motec...@googlegroups.com, pge...@soldevelo.com, mtok...@soldevelo.com, pzur...@soldevelo.com

See <http://ci.motechproject.org/job/Platform-FunctionalTests/93/>

------------------------------------------
[...truncated 587 lines...]
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
May 22, 2016 1:31:48 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory <http://ci.motechproject.org/job/Platform-FunctionalTests/ws/tests/target/tomcat0/webapps/ROOT> has finished in 182 ms
May 22, 2016 1:31:48 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
May 22, 2016 1:31:48 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 13692 ms
2016-05-22 01:31:50,359 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-22 01:31:52,369 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-22 01:31:54,376 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-22 01:31:56,381 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-22 01:31:58,387 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-22 01:32:00,392 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-22 01:32:02,399 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-22 01:32:04,405 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
[INFO] Initializing Spring FrameworkServlet 'servlet_3'
2016-05-22 01:32:06,411 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
[INFO] Initializing Spring FrameworkServlet 'servlet_5'

[INFO] Initialized servlet for org.motechproject.motech-platform-dataservices [123]

2016-05-22 01:32:08,459 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initialized servlet for org.motechproject.motech-platform-dataservices-web [17]

2016-05-22 01:32:11,198 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds
2016-05-22 01:32:13,205 WARN [org.motechproject.testing.utils.PollingHttpClient] Wrong response status: 404. Thread stopped for 2 seconds

[INFO] Initializing Spring FrameworkServlet 'servlet_13'

[INFO] Initialized servlet for org.motechproject.commcare [112]

[INFO] Initializing Spring FrameworkServlet 'servlet_15'
[INFO] Initialized servlet for org.motechproject.motech-admin [75]

[INFO] Initializing Spring FrameworkServlet 'servlet_16'

[INFO] Initialized servlet for org.motechproject.motech-scheduler [107]
2016-05-22 01:33:16,781 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-22 01:33:17,032 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-22 01:33:17,106 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-22 01:33:18,623 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-22 01:34:18,888 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-22 01:34:20,906 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-22 01:34:20,938 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-22 01:34:20,972 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-22 01:34:21,018 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-22 01:34:21,123 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-22 01:34:21,758 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: editEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-22 01:34:22,355 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-22 01:34:22,584 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-22 01:34:22,651 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-22 01:34:23,523 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.id: modulelink_data-services
2016-05-22 01:35:23,919 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-22 01:35:25,962 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-22 01:35:25,995 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-22 01:35:26,041 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-22 01:35:26,111 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-22 01:35:26,262 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saving screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
2016-05-22 01:35:27,241 DEBUG [org.motechproject.testing.uifunctionaltests.DataServicesUIFT] Saved screenshot: newEntityTestorg.motechproject.testing.uifunctionaltests.DataServicesUIFT
Tests run: 2, Failures: 0, Errors: 2, Skipped: 0, Time elapsed: 228.893 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.DataServicesUIFT
editEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 66.196 sec <<< ERROR!

org.openqa.selenium.TimeoutException: Timed out after 60 seconds waiting for element to be clickable: By.id: modulelink_data-services
Build info: version: '2.39.0', revision: '14fa800511cc5d66d426e08b0b2ab926c7ed7398', time: '2013-12-16 13:18:38'
System info: host: '9c18a436bea2', ip: '172.17.0.19', os.name: 'Linux', os.arch: 'amd64', os.version: '3.13.0-29-generic', java.version: '1.8.0_60'
Driver info: driver.version: unknown
at sun.reflect.GeneratedConstructorAccessor9.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:422)
at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:193)
at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:145)
at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:554)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:307)
at org.openqa.selenium.remote.RemoteWebDriver.findElementById(RemoteWebDriver.java:348)
at org.openqa.selenium.By$ById.findElement(By.java:220)
at org.openqa.selenium.remote.RemoteWebDriver.findElement(RemoteWebDriver.java:299)
at org.openqa.selenium.support.ui.ExpectedConditions.findElement(ExpectedConditions.java:730)
at org.openqa.selenium.support.ui.ExpectedConditions.access$000(ExpectedConditions.java:39)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:130)
at org.openqa.selenium.support.ui.ExpectedConditions$4.apply(ExpectedConditions.java:126)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:528)
at org.openqa.selenium.support.ui.ExpectedConditions$17.apply(ExpectedConditions.java:521)
at org.openqa.selenium.support.ui.FluentWait.until(FluentWait.java:208)
at org.motechproject.uitest.page.AbstractBasePage.clickWhenVisible(AbstractBasePage.java:228)
at org.motechproject.uitest.page.MotechPage.goToDataServices(MotechPage.java:25)
at org.motechproject.testing.uifunctionaltests.DataServicesUIFT.initialize(DataServicesUIFT.java:26)

newEntityTest(org.motechproject.testing.uifunctionaltests.DataServicesUIFT) Time elapsed: 65.467 sec <<< ERROR!

2016-05-22 01:35:29,709 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 15375

Only local connections are allowed.

2016-05-22 01:36:33,172 DEBUG [org.motechproject.testing.uifunctionaltests.HelloWorldFT] Hello world!
2016-05-22 01:36:34,140 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-22 01:36:34,396 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-22 01:36:34,471 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 67.474 sec - in org.motechproject.testing.uifunctionaltests.HelloWorldFT

2016-05-22 01:36:36,987 INFO [org.apache.commons.vfs2.impl.StandardFileSystemManager] Using "/tmp/vfs_cache" as temporary files store.
Starting ChromeDriver 2.14.313457 (3d645c400edf2e2c500566c9aa096063e707c9cf) on port 16321

Only local connections are allowed.

2016-05-22 01:37:39,993 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.name: j_username
2016-05-22 01:37:40,243 DEBUG [org.motechproject.uitest.page.LoginPage] Clicking on: By.selector: input.btn.btn-primary
2016-05-22 01:37:40,315 DEBUG [org.motechproject.uitest.page.LoginPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-22 01:37:41,611 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.linkText: REST API
2016-05-22 01:37:41,678 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.linkText: REST API
2016-05-22 01:37:41,769 DEBUG [org.motechproject.uitest.page.RestApiPage] Waiting until element is clickable: By.id: modulelink_rest-docs
2016-05-22 01:38:42,206 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be gone: By.className: blockUI
2016-05-22 01:38:44,221 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting for element to be visisble: By.selector: span.ng-binding
2016-05-22 01:38:44,254 DEBUG [org.motechproject.uitest.page.MotechPage] Waiting until element is clickable: By.selector: span.ng-binding
2016-05-22 01:38:44,287 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.selector: span.ng-binding
2016-05-22 01:38:44,331 DEBUG [org.motechproject.uitest.page.MotechPage] Clicking on: By.xpath: //a[@href='j_spring_security_logout']
2016-05-22 01:38:44,416 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saving screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
2016-05-22 01:38:45,074 DEBUG [org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT] Saved screenshot: shouldOpenRestApiDocumentationorg.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 129.038 sec <<< FAILURE! - in org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT
shouldOpenRestApiDocumentation(org.motechproject.testing.uifunctionaltests.RestApiDocumentationUIFT) Time elapsed: 65.892 sec <<< ERROR!

May 22, 2016 1:38:46 AM org.apache.catalina.core.StandardServer await

INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.

May 22, 2016 1:38:46 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-8080"]
May 22, 2016 1:38:46 AM org.apache.catalina.core.StandardService stopInternal

INFO: Stopping service Catalina
[INFO] Destroying Spring FrameworkServlet 'servlet_3'
[INFO] Destroying Spring FrameworkServlet 'servlet_1'

May 22, 2016 1:38:46 AM org.apache.catalina.core.ApplicationContext log

INFO: Destroying Spring FrameworkServlet 'bootstrap-servlet'

May 22, 2016 1:38:46 AM org.apache.catalina.core.ApplicationContext log

INFO: Closing Spring root WebApplicationContext

May 22, 2016 1:38:46 AM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc

May 22, 2016 1:38:46 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads