It takes several seconds(10 - 20 sec.) between WildFly container started and deployement.

test.md

The following table is GMT.

Stage	Time
Sending client request	14:47:52
My WildFly container boot	14:47:53,395
(1) My WildFly container started	14:47:59,880
(2) App deployment start	14:48:17,550

Why does it takes sevral seconds by the time deployment(between (1) - (2))?

Arquillian Cube logs

The following logs is JST.

SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
SLF4J: Defaulting to no-operation (NOP) logger implementation
SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
Mar 24, 2015 11:47:48 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 1 * Sending client request on thread Test worker
1 > GET http://localhost:2375/v1.5.0/_ping

Mar 24, 2015 11:47:48 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 2 * Client response received on thread Test worker
2 < 200
2 < Content-Length: 2
2 < Content-Type: application/json; charset=utf-8
2 < Date: Tue, 24 Mar 2015 14:47:48 GMT
OK

Mar 24, 2015 11:47:48 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 3 * Sending client request on thread Test worker
3 > POST http://localhost:2375/v1.5.0/containers/create?name=db
3 > Accept: application/json
3 > Content-Type: application/json
{"name":"db","dns":null,"capAdd":null,"capDrop":null,"Hostname":"","User":"","Memory":0,"MemorySwap":0,"CpuShares":0,"AttachStdin":false,"AttachStdout":false,"AttachStderr":false,"PortSpecs":null,"Tty":false,"OpenStdin":false,"StdinOnce":false,"Env":["POSTGRES_USER=morecat","POSTGRES_PASSWORD=morecat"],"Cmd":null,"Image":"emag/morecat-db:latest","Volumes":{},"VolumesFrom":[],"WorkingDir":"","DisableNetwork":false,"ExposedPorts":{"5432/tcp":{}},"HostConfig":{"Binds":null,"Links":null,"LxcConf":null,"PortBindings":null,"PublishAllPorts":false,"Privileged":false,"Dns":null,"DnsSearch":null,"VolumesFrom":null,"ContainerIDFile":null,"CapAdd":null,"CapDrop":null,"RestartPolicy":null,"NetworkMode":null,"Devices":null}}

Mar 24, 2015 11:47:49 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 4 * Client response received on thread Test worker
4 < 201
4 < Content-Length: 90
4 < Content-Type: application/json
4 < Date: Tue, 24 Mar 2015 14:47:49 GMT
{"Id":"5d4e68d63b092507bbf3daae8c81b34fdb87f8d07f27bebfab670da67bb9b72f","Warnings":null}


Mar 24, 2015 11:47:49 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 5 * Sending client request on thread Test worker
5 > POST http://localhost:2375/v1.5.0/containers/db/start
5 > Accept: application/json
5 > Content-Type: application/json
{"containerId":"db","Binds":[],"Links":[],"LxcConf":null,"PortBindings":{"5432/tcp":[{"HostIp":"","HostPort":"5433"}]},"PublishAllPorts":false,"Privileged":false,"Dns":null,"DnsSearch":null,"VolumesFrom":null,"NetworkMode":"bridge","Devices":null,"RestartPolicy":null,"CapAdd":null,"CapDrop":null}

Mar 24, 2015 11:47:49 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 6 * Client response received on thread Test worker
6 < 204
6 < Date: Tue, 24 Mar 2015 14:47:49 GMT

Mar 24, 2015 11:47:49 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 7 * Sending client request on thread Test worker
7 > GET http://localhost:2375/v1.5.0/containers/db/json
7 > Accept: application/json

Mar 24, 2015 11:47:49 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 8 * Client response received on thread Test worker
8 < 200
8 < Content-Type: application/json
8 < Date: Tue, 24 Mar 2015 14:47:49 GMT
8 < Transfer-Encoding: chunked
{"State":{"Running":true,"Paused":false,"Restarting":false,"OOMKilled":false,"Pid":7270,"ExitCode":0,"Error":"","StartedAt":"2015-03-24T14:47:49.357741496Z","FinishedAt":"0001-01-01T00:00:00Z"},"ID":"5d4e68d63b092507bbf3daae8c81b34fdb87f8d07f27bebfab670da67bb9b72f","Created":"2015-03-24T14:47:48.911314726Z","Path":"/docker-entrypoint.sh","Args":["postgres"],"Config":{"Hostname":"5d4e68d63b09","Domainname":"","User":"","Memory":0,"MemorySwap":0,"CpuShares":0,"Cpuset":"","AttachStdin":false,"AttachStdout":false,"AttachStderr":false,"PortSpecs":null,"ExposedPorts":{"5432/tcp":{}},"Tty":false,"OpenStdin":false,"StdinOnce":false,"Env":["POSTGRES_USER=morecat","POSTGRES_PASSWORD=morecat","PATH=/usr/lib/postgresql/9.4/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin","LANG=en_US.utf8","PG_MAJOR=9.4","PG_VERSION=9.4.1-1.pgdg70+1","PGDATA=/var/lib/postgresql/data"],"Cmd":["postgres"],"Image":"emag/morecat-db:latest","Volumes":{"/var/lib/postgresql/data":{}},"WorkingDir":"","Entrypoint":["/docker-entrypoint.sh"],"NetworkDisabled":false,"MacAddress":"","OnBuild":null},"Image":"a9892998023c1c0cb965dbfd8ad662bbe4c2dd9f1e3fe0285ed7d859b9d50cb4","NetworkSettings":{"IPAddress":"172.17.1.114","IPPrefixLen":16,"MacAddress":"02:42:ac:11:01:72","LinkLocalIPv6Address":"fe80::42:acff:fe11:172","LinkLocalIPv6PrefixLen":64,"GlobalIPv6Address":"","GlobalIPv6PrefixLen":0,"Gateway":"172.17.42.1","IPv6Gateway":"","Bridge":"docker0","PortMapping":null,"Ports":{"5432/tcp":[{"HostIp":"0.0.0.0","HostPort":"5433"}]}},"ResolvConfPath":"/var/lib/docker/containers/5d4e68d63b092507bbf3daae8c81b34fdb87f8d07f27bebfab670da67bb9b72f/resolv.conf","HostnamePath":"/var/lib/docker/containers/5d4e68d63b092507bbf3daae8c81b34fdb87f8d07f27bebfab670da67bb9b72f/hostname","HostsPath":"/var/lib/docker/containers/5d4e68d63b092507bbf3daae8c81b34fdb87f8d07f27bebfab670da67bb9b72f/hosts","Name":"/db","Driver":"devicemapper","ExecDriver":"native-0.2","MountLabel":"","ProcessLabel":"","AppArmorProfile":"","RestartCount":0,"UpdateDns":false,"Volumes":{"/var/lib/postgresql/data":"/var/lib/docker/vfs/dir/728516cc31245d9bfe498aa4dddfbf4cd14915ed261982fac2d768cef48b7eb9"},"VolumesRW":{"/var/lib/postgresql/data":true},"AppliedVolumesFrom":null,"HostConfig":{"Binds":[],"ContainerIDFile":"","LxcConf":null,"Privileged":false,"PortBindings":{"5432/tcp":[{"HostIp":"","HostPort":"5433"}]},"Links":null,"PublishAllPorts":false,"Dns":null,"DnsSearch":null,"ExtraHosts":null,"VolumesFrom":null,"Devices":null,"NetworkMode":"bridge","IpcMode":"","PidMode":"","CapAdd":null,"CapDrop":null,"RestartPolicy":{"Name":"","MaximumRetryCount":0},"SecurityOpt":null,"ReadonlyRootfs":false}}

Mar 24, 2015 11:47:52 PM org.jboss.arquillian.container.impl.MapObject populate
WARNING: Configuration contain properties not supported by the backing object org.jboss.as.arquillian.container.remote.RemoteContainerConfiguration
Unused property entries: {target=wildfly:8.2.0.Final:remote}
Supported property names: [managementAddress, password, managementPort, managementProtocol, username]
Mar 24, 2015 11:47:52 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 9 * Sending client request on thread Test worker
9 > GET http://localhost:2375/v1.5.0/_ping

Mar 24, 2015 11:47:52 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 10 * Client response received on thread Test worker
10 < 200
10 < Content-Length: 2
10 < Content-Type: application/json; charset=utf-8
10 < Date: Tue, 24 Mar 2015 14:47:52 GMT
OK

Mar 24, 2015 11:47:52 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 11 * Sending client request on thread Test worker
11 > POST http://localhost:2375/v1.5.0/containers/create?name=morecat
11 > Accept: application/json
11 > Content-Type: application/json
{"name":"morecat","dns":null,"capAdd":null,"capDrop":null,"Hostname":"","User":"","Memory":0,"MemorySwap":0,"CpuShares":0,"AttachStdin":false,"AttachStdout":false,"AttachStderr":false,"PortSpecs":null,"Tty":false,"OpenStdin":false,"StdinOnce":false,"Env":["DATASOURCE_NAME=morecatDS","DB_NAME=morecat","DB_USER=morecat","WILDFLY_ADMIN_USER=admin","WILDFLY_ADMIN_PASSWORD=Admin#70365"],"Cmd":null,"Image":"emag/morecat:latest","Volumes":{},"VolumesFrom":[],"WorkingDir":"","DisableNetwork":false,"ExposedPorts":{"8080/tcp":{},"9990/tcp":{}},"HostConfig":{"Binds":null,"Links":null,"LxcConf":null,"PortBindings":null,"PublishAllPorts":false,"Privileged":false,"Dns":null,"DnsSearch":null,"VolumesFrom":null,"ContainerIDFile":null,"CapAdd":null,"CapDrop":null,"RestartPolicy":null,"NetworkMode":null,"Devices":null}}

Mar 24, 2015 11:47:52 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 12 * Client response received on thread Test worker
12 < 201
12 < Content-Length: 90
12 < Content-Type: application/json
12 < Date: Tue, 24 Mar 2015 14:47:52 GMT
{"Id":"438e2138e03123d4a0b8d0c3118d6db42a2d358e2c49b576593402ea82720ac0","Warnings":null}


Mar 24, 2015 11:47:52 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 13 * Sending client request on thread Test worker
13 > POST http://localhost:2375/v1.5.0/containers/morecat/start
13 > Accept: application/json
13 > Content-Type: application/json
{"containerId":"morecat","Binds":[],"Links":["db:db"],"LxcConf":null,"PortBindings":{"8080/tcp":[{"HostIp":"","HostPort":"8081"}],"9990/tcp":[{"HostIp":"","HostPort":"9991"}]},"PublishAllPorts":false,"Privileged":false,"Dns":null,"DnsSearch":null,"VolumesFrom":null,"NetworkMode":"bridge","Devices":null,"RestartPolicy":null,"CapAdd":null,"CapDrop":null}

Mar 24, 2015 11:47:52 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 14 * Client response received on thread Test worker
14 < 204
14 < Date: Tue, 24 Mar 2015 14:47:52 GMT

Mar 24, 2015 11:47:52 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 15 * Sending client request on thread Test worker
15 > GET http://localhost:2375/v1.5.0/containers/morecat/json
15 > Accept: application/json

Mar 24, 2015 11:47:52 PM org.glassfish.jersey.filter.LoggingFilter log
INFO: 16 * Client response received on thread Test worker
16 < 200
16 < Content-Type: application/json
16 < Date: Tue, 24 Mar 2015 14:47:52 GMT
16 < Transfer-Encoding: chunked
{"State":{"Running":true,"Paused":false,"Restarting":false,"OOMKilled":false,"Pid":7436,"ExitCode":0,"Error":"","StartedAt":"2015-03-24T14:47:52.522828224Z","FinishedAt":"0001-01-01T00:00:00Z"},"ID":"438e2138e03123d4a0b8d0c3118d6db42a2d358e2c49b576593402ea82720ac0","Created":"2015-03-24T14:47:52.080790444Z","Path":"sh","Args":["/opt/jboss/wildfly/customization/execute.sh"],"Config":{"Hostname":"438e2138e031","Domainname":"","User":"jboss","Memory":0,"MemorySwap":0,"CpuShares":0,"Cpuset":"","AttachStdin":false,"AttachStdout":false,"AttachStderr":false,"PortSpecs":null,"ExposedPorts":{"8080/tcp":{},"9990/tcp":{}},"Tty":false,"OpenStdin":false,"StdinOnce":false,"Env":["DATASOURCE_NAME=morecatDS","DB_NAME=morecat","DB_USER=morecat","WILDFLY_ADMIN_USER=admin","WILDFLY_ADMIN_PASSWORD=Admin#70365","PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin","JAVA_HOME=/usr/lib/jvm/java","WILDFLY_VERSION=8.2.0.Final","JBOSS_HOME=/opt/jboss/wildfly"],"Cmd":["sh","/opt/jboss/wildfly/customization/execute.sh"],"Image":"emag/morecat:latest","Volumes":null,"WorkingDir":"/opt/jboss","Entrypoint":null,"NetworkDisabled":false,"MacAddress":"","OnBuild":null},"Image":"4a68ce8250978f797394a40904cc5f0c8e02e1b6bc20e3b3989fce5c5a1b849f","NetworkSettings":{"IPAddress":"172.17.1.115","IPPrefixLen":16,"MacAddress":"02:42:ac:11:01:73","LinkLocalIPv6Address":"fe80::42:acff:fe11:173","LinkLocalIPv6PrefixLen":64,"GlobalIPv6Address":"","GlobalIPv6PrefixLen":0,"Gateway":"172.17.42.1","IPv6Gateway":"","Bridge":"docker0","PortMapping":null,"Ports":{"8080/tcp":[{"HostIp":"0.0.0.0","HostPort":"8081"}],"9990/tcp":[{"HostIp":"0.0.0.0","HostPort":"9991"}]}},"ResolvConfPath":"/var/lib/docker/containers/438e2138e03123d4a0b8d0c3118d6db42a2d358e2c49b576593402ea82720ac0/resolv.conf","HostnamePath":"/var/lib/docker/containers/438e2138e03123d4a0b8d0c3118d6db42a2d358e2c49b576593402ea82720ac0/hostname","HostsPath":"/var/lib/docker/containers/438e2138e03123d4a0b8d0c3118d6db42a2d358e2c49b576593402ea82720ac0/hosts","Name":"/morecat","Driver":"devicemapper","ExecDriver":"native-0.2","MountLabel":"","ProcessLabel":"","AppArmorProfile":"","RestartCount":0,"UpdateDns":false,"Volumes":{},"VolumesRW":{},"AppliedVolumesFrom":null,"HostConfig":{"Binds":[],"ContainerIDFile":"","LxcConf":null,"Privileged":false,"PortBindings":{"8080/tcp":[{"HostIp":"","HostPort":"8081"}],"9990/tcp":[{"HostIp":"","HostPort":"9991"}]},"Links":null,"PublishAllPorts":false,"Dns":null,"DnsSearch":null,"ExtraHosts":null,"VolumesFrom":null,"Devices":null,"NetworkMode":"bridge","IpcMode":"","PidMode":"","CapAdd":null,"CapDrop":null,"RestartPolicy":{"Name":"","MaximumRetryCount":0},"SecurityOpt":null,"ReadonlyRootfs":false}}

Mar 24, 2015 11:48:16 PM org.xnio.Xnio <clinit>
INFO: XNIO version 3.3.0.Final
Mar 24, 2015 11:48:16 PM org.xnio.nio.NioXnio <clinit>
INFO: XNIO NIO Implementation Version 3.3.0.Final
Mar 24, 2015 11:48:16 PM org.jboss.remoting3.EndpointImpl <clinit>
INFO: JBoss Remoting version 4.0.6.Final

My WildFly Container log

The following log is GMT.

[...]
14:47:59,880 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015874: WildFly 8.2.0.Final "Tweek" started in 2566ms - Started 193 of 243 services (82 services are lazy, passive or on-demand)
14:48:17,550 INFO  [org.jboss.as.repository] (management-handler-thread - 1) JBAS014900: Content added at location /opt/jboss/wildfly/standalone/data/content/c1/1c4d89fa4f26a4696beffe5cdbbe373df770b5/content

How to reproduce

My Environments

• Fedora 21 64bit
• JDK: Oracle JDK 8u40(but my wildfly container using Open JDK 8u31)
• JDK 8 Required for compilation
• Arquillian Cube 1.0.0.Alpha3

Running test

git clone https://github.com/emag/morecat.git
cd morecat
git checkout 1.0.0-update
./gradlew test -b morecat-api/build.gradle

arquillian.xml

https://github.com/emag/morecat/blob/1.0.0-update/morecat-api/src/test/resources/arquillian.xml

<?xml version="1.0" encoding="UTF-8"?>
<arquillian xmlns="http://jboss.org/schema/arquillian"
            xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
            xsi:schemaLocation="http://jboss.org/schema/arquillian
        http://jboss.org/schema/arquillian/arquillian_1_0.xsd">

  <extension qualifier="docker">
    <property name="serverVersion">1.5.0</property>
    <property name="serverUri">http://localhost:2375</property>
    <property name="autoStartContainers">db</property>
    <property name="dockerContainers">
      morecat:
        image: emag/morecat:latest
        await:
          strategy: polling
          sleepPollingTime: 3 s
          iterations: 20
        links:
          - db:db
        env: [DATASOURCE_NAME=morecatDS, DB_NAME=morecat, DB_USER=morecat, WILDFLY_ADMIN_USER=admin, WILDFLY_ADMIN_PASSWORD=Admin#70365]
        exposedPorts: [8080/tcp, 9990/tcp]
        portBindings: [8081->8080/tcp, 9991->9990/tcp]
      db:
        image: emag/morecat-db:latest
        await:
          strategy: polling
        env: [POSTGRES_USER=morecat, POSTGRES_PASSWORD=morecat]
        exposedPorts: [5432/tcp]
        portBindings: [5433->5432/tcp]
    </property>
  </extension>

  <container qualifier="morecat" default="true">
    <configuration>
      <property name="target">wildfly:8.2.0.Final:remote</property>
      <property name="managementPort">9991</property>
      <property name="username">admin</property>
      <property name="password">Admin#70365</property>
    </configuration>
  </container>

</arquillian>