[arquillian-issues] [JBoss JIRA] (ARQ-2090) Arquillian test takes more than 5 minutes

nicolas duminil (JIRA) issues at jboss.org
Wed Apr 5 04:31:00 EDT 2017


    [ https://issues.jboss.org/browse/ARQ-2090?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13389100#comment-13389100 ] 

nicolas duminil commented on ARQ-2090:
--------------------------------------

The post above doesn't concenrn WLP (WebSphere Liberty Profile) but WAS 8.5.5. WLP is not production ready and hence we don't use it. And running the same test with IBM Java 7 has exactly the same result.
Without Arquillian test the build is instantly, as shown above in the console output. The part which takes the 5 minutes starts with the message "Running be.alphacredit.tests.TestXSDValidationArquillian" at11:09:59. It takes one minute to load the POM, import the dependencies, create the archive and add the dependedncies to the archive. At 11:11:00 the message "Web archive created" shows rhe return from the method createTestArchive().
Then during 31 seconds different warnings are displayed. At 11:11:31 the message "INFO: Target server for deployment is WebSphere:cell=cac40Node01Cell,node=cac40Node01,server=server1" is displayed and then the deployment of the archive on the remote application server takes 3 minutes and 8 seconds until the message "INFO: Application was started on the following targets: WebSphere:cell=cac40Node01Cell,node=cac40Node01,server=server1" is displayed.
Further, when it comes to the real deployment, in the pre-integration-test phase of maven, the same archive (well almost the same) takes a couple of seconds. The remote server is local, it runs on the same machine.
So the question is why the Arquillian deployment takes so long and one of the possible answers is that the server is scanning the archive for annotations. This is what meta-data=complete is supposed to avoid, but it doesn't. Hence my question: does anyone know why ?

Kind regards,
Nicolas DUMINIL

> Arquillian test takes more than 5 minutes
> -----------------------------------------
>
>                 Key: ARQ-2090
>                 URL: https://issues.jboss.org/browse/ARQ-2090
>             Project: Arquillian
>          Issue Type: Bug
>          Components: WebSphere Containers
>         Environment: arquillian-was-remote-8.5:1.0.0-Final, WebSphere 8.5.5 on Windows 10, 
> Java: IBM J9 VM (build 2.6, JRE 1.6.0 Windows 8 amd64-64 Compressed References 20130301_140166 (JIT enabled, AOT enabled)
>            Reporter: nicolas duminil
>            Assignee: Gerhard Poul
>
> The execution of an Arquillian test (an empty test) takes more than 5 minutes. here is the test:
> -{{@RunWith(Arquillian.class)
> public class TestXSDValidationArquillian
> {
>   private static final Logger slf4jLogger = LoggerFactory.getLogger(TestXSDValidationArquillian.class);
>   
>   @Deployment
>   public static Archive<?> createTestArchive()
>   {
>     slf4jLogger.info("Loading pom.xml");
>     PomEquippedResolveStage mavenResolver =  Maven.resolver().loadPomFromFile("pom.xml");
>     slf4jLogger.info("pom.xml loaded. Now importing dependencies");
>     File[] libs = mavenResolver.importRuntimeAndTestDependencies().resolve().withTransitivity().asFile();
>     slf4jLogger.info("Dependencies loaded. Creating web archive");
>     WebArchive war = ShrinkWrap.create(WebArchive.class, "arquillian-test.war");
>     slf4jLogger.info("Web archive created. Now, adding files to the archive");
>     for (File file : libs) 
>       war.addAsLibrary(file);
>     slf4jLogger.info("Web archive created");
>     return war;
>   }-
>   
>   @Test
>   public void test1(){}
> }}
> An here is the console output:
> -------------------------------------------------------
>  T E S T S
> -------------------------------------------------------
> Running be.alphacredit.tests.TestXSDValidationArquillian
> 04-Apr-2017 11:09:59 null null
> WARNING: ADMC0046W
> 04-Apr-2017 11:10:00 null null
> WARNING: Could not find tmx4jTransform.jar in null/etc/tmx4jTransform.jar - Interoperability to older versions of WebSphere is disabled
> 04-Apr-2017 11:10:00 null null
> INFO: ssl.disable.url.hostname.verification.CWPKI0027I
> Loading pom.xml
> pom.xml loaded. Now importing dependencies
> 04-Apr-2017 11:10:02 org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
> WARNING: Failed downloading org/jboss/arquillian/container/arquillian-was-remote-8.5/1.0.0.Final-SNAPSHOT/maven-metadata.xml from http://repo1.maven.org/maven2/. Reason:
> org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.arquillian.container:arquillian-was-remote-8.5:1.0.0.Final-SNAPSHOT/maven-metadata.xml in central (http://repo1.maven.org/maven2)
> 04-Apr-2017 11:10:02 org.jboss.shrinkwrap.resolver.impl.maven.logging.LogTransferListener transferFailed
> WARNING: Failed downloading org/jboss/arquillian/container/arquillian-parent-was/1.0.0.Final-SNAPSHOT/maven-metadata.xml from http://repo1.maven.org/maven2/. Reason:
> org.eclipse.aether.transfer.MetadataNotFoundException: Could not find metadata org.jboss.arquillian.container:arquillian-parent-was:1.0.0.Final-SNAPSHOT/maven-metadata.xml in central (http://repo1.maven.org/maven2)
> Dependencies loaded. Creating web archive
> Web archive created. Now, adding files to the archive
> Web archive created
> 04-Apr-2017 11:11:00 com.ibm.ws.amm.scan.util.AnnotationInfoDefaultVisitor visitAnnotation
> WARNING: [ com.ibm.ws.amm.scan.util.InfoVisitor at -323862990 ] for method [ com.ibm.ws.amm.scan.util.info.impl.MethodInfoImpl at -126388353 ( com.ibm.ws.amm.scan.util.info.impl.test.AnnotationTypeReaderAnno.nestedAnno ) ] Name [ null ] Description [ Lcom/ibm/ws/amm/scan/util/info/impl/test/AnnotationTypeReaderNestedAnno; ] Call in violation of protocol
> 04-Apr-2017 11:11:03 com.ibm.ws.amm.scan.util.AnnotationInfoDefaultVisitor visitAnnotation
> WARNING: [ com.ibm.ws.amm.scan.util.InfoVisitor at -323862990 ] for method [ com.ibm.ws.amm.scan.util.info.impl.MethodInfoImpl at 1495822839 ( javax.persistence.AssociationOverride.joinTable ) ] Name [ null ] Description [ Ljavax/persistence/JoinTable; ] Call in violation of protocol
> 04-Apr-2017 11:11:03 com.ibm.ws.amm.scan.util.AnnotationInfoDefaultVisitor visitAnnotation
> WARNING: [ com.ibm.ws.amm.scan.util.InfoVisitor at -323862990 ] for method [ com.ibm.ws.amm.scan.util.info.impl.MethodInfoImpl at 1522521260 ( javax.servlet.annotation.ServletSecurity.value ) ] Name [ null ] Description [ Ljavax/servlet/annotation/HttpConstraint; ] Call in violation of protocol
> 04-Apr-2017 11:11:31 org.jboss.arquillian.container.was.remote_8_5.WebSphereRemoteContainer deploy
> INFO: Target server for deployment is WebSphere:cell=cac40Node01Cell,node=cac40Node01,server=server1
> 04-Apr-2017 11:14:39 org.jboss.arquillian.container.was.remote_8_5.WebSphereRemoteContainer deploy
> INFO: Application was started on the following targets: WebSphere:cell=cac40Node01Cell,node=cac40Node01,server=server1
> Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 291.094 sec
> Results :
> Tests run: 1, Failures: 0, Errors: 0, Skipped: 0
> As one may see, test starts at 11:09:59 and finishes at 11:14:39 and takes 291.094 seconds. I have googled and found some posts concerning the metadata-complete="true" that I added to my web.xml, as follows:
> <?xml version="1.0" encoding="UTF-8"?>
> <web-app id="WebApp_ID" version="3.0" xmlns="http://java.sun.com/xml/ns/javaee" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="http://java.sun.com/xml/ns/javaee http://java.sun.com/xml/ns/javaee/web-app_3_0.xsd" metadata-complete="true"/>
> but it didn't help. It seems to be related to the IBM Java 6 but this is the JVM that one is supposed to use with IBM WebSphere. Any idea of what might be the problem here ?
> Kind regards,
> Nicolas DUMINIL



--
This message was sent by Atlassian JIRA
(v7.2.3#72005)


More information about the arquillian-issues mailing list