Created
February 24, 2020 14:19
-
-
Save vmassol/7e269520254d2f21ce0bcb65014b4302 to your computer and use it in GitHub Desktop.
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| ... | |
| 04:20:29.482 [main] INFO o.x.t.d.i.j.XWikiDockerExtension - (*) Starting database [POSTGRESQL]... | |
| 04:20:29.508 [main] DEBUG o.t.images.AbstractImagePullPolicy - Using locally available and not pulling image: postgres:12 | |
| 04:20:29.508 [main] INFO o.x.t.d.i.j.AbstractContainerExecutor - Docker image used: [postgres:12] | |
| 04:20:29.512 [main] INFO o.x.t.d.i.junit5.DockerTestUtils - Pulling image [postgres:12] | |
| 04:20:29.516 [main] DEBUG o.t.utility.RegistryAuthLocator - Looking up auth config for image: postgres:12 | |
| 04:20:29.516 [main] DEBUG o.t.utility.RegistryAuthLocator - RegistryAuthLocator has configFile: /root/.docker/config.json (does not exist) and commandPathPrefix: | |
| 04:20:29.516 [main] WARN o.t.utility.RegistryAuthLocator - Failure when attempting to lookup auth config (dockerImageName: postgres:12, configFile: /root/.docker/config.json. Falling back to docker-java default behaviour. Exception message: /root/.docker/config.json (No such file or directory) | |
| 04:20:29.516 [main] DEBUG o.t.d.a.AuthDelegatingDockerClientConfig - Effective auth config [null] | |
| 04:20:31.108 [main] DEBUG o.t.utility.RegistryAuthLocator - Looking up auth config for image: postgres:12 | |
| 04:20:31.109 [main] DEBUG o.t.utility.RegistryAuthLocator - RegistryAuthLocator has configFile: /root/.docker/config.json (does not exist) and commandPathPrefix: | |
| 04:20:31.109 [main] WARN o.t.utility.RegistryAuthLocator - Failure when attempting to lookup auth config (dockerImageName: postgres:12, configFile: /root/.docker/config.json. Falling back to docker-java default behaviour. Exception message: /root/.docker/config.json (No such file or directory) | |
| 04:20:31.109 [main] DEBUG o.t.d.a.AuthDelegatingDockerClientConfig - Effective auth config [null] | |
| 04:20:31.636 [main] TRACE o.testcontainers.utility.AuditLogger - CREATE_NETWORK action with image: null, containerId: null | |
| 04:20:32.879 [main] TRACE o.testcontainers.utility.AuditLogger - CREATE action with image: postgres:12, containerId: 5ac1dca32fee50f2413292f53d6bdb4be12960713731cafbf9e81687b209fb8b | |
| 04:20:35.899 [main] TRACE o.testcontainers.utility.AuditLogger - START action with image: null, containerId: 5ac1dca32fee50f2413292f53d6bdb4be12960713731cafbf9e81687b209fb8b | |
| 04:20:35.905 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: The files belonging to this database system will be owned by user "postgres". | |
| 04:20:35.906 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: This user must also own the server process. | |
| 04:20:35.906 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:35.907 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: The database cluster will be initialized with locale "en_US.utf8". | |
| 04:20:35.907 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: The default text search configuration will be set to "english". | |
| 04:20:35.907 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:35.908 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: Data page checksums are disabled. | |
| 04:20:35.908 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:35.908 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: fixing permissions on existing directory /var/lib/postgresql/data ... ok | |
| 04:20:35.909 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: creating subdirectories ... ok | |
| 04:20:35.909 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: selecting dynamic shared memory implementation ... posix | |
| 04:20:35.910 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: selecting default max_connections ... 100 | |
| 04:20:35.910 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: selecting default shared_buffers ... 128MB | |
| 04:20:35.914 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: The files belonging to this database system will be owned by user "postgres". | |
| 04:20:35.914 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: creating subdirectories ... ok | |
| 04:20:35.914 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: selecting default shared_buffers ... 128MB | |
| 04:20:35.915 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: selecting default max_connections ... 100 | |
| 04:20:35.915 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: selecting dynamic shared memory implementation ... posix | |
| 04:20:35.915 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: fixing permissions on existing directory /var/lib/postgresql/data ... ok | |
| 04:20:35.915 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:35.915 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: Data page checksums are disabled. | |
| 04:20:35.915 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:35.915 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: The default text search configuration will be set to "english". | |
| 04:20:35.916 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: The database cluster will be initialized with locale "en_US.utf8". | |
| 04:20:35.916 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:35.916 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: This user must also own the server process. | |
| 04:20:35.947 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: selecting default time zone ... Etc/UTC | |
| 04:20:35.947 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: selecting default time zone ... Etc/UTC | |
| 04:20:35.948 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: creating configuration files ... ok | |
| 04:20:35.957 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: creating configuration files ... ok | |
| 04:20:36.159 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: running bootstrap script ... ok | |
| 04:20:36.159 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: running bootstrap script ... ok | |
| 04:20:37.082 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: performing post-bootstrap initialization ... ok | |
| 04:20:37.082 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: performing post-bootstrap initialization ... ok | |
| 04:20:39.383 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: syncing data to disk ... ok | |
| 04:20:39.383 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: syncing data to disk ... ok | |
| 04:20:39.383 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: pg_ctl -D /var/lib/postgresql/data -l logfile start | |
| 04:20:39.383 [main] DEBUG o.t.c.output.WaitingConsumer - STDERR: You can change this by editing pg_hba.conf or using the option -A, or | |
| 04:20:39.383 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:39.384 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:39.384 [main] DEBUG o.t.c.output.WaitingConsumer - STDERR: --auth-local and --auth-host, the next time you run initdb. | |
| 04:20:39.384 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:39.384 [main] DEBUG o.t.c.output.WaitingConsumer - STDERR: initdb: warning: enabling "trust" authentication for local connections | |
| 04:20:39.384 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: Success. You can now start the database server using: | |
| 04:20:39.384 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:39.384 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: Success. You can now start the database server using: | |
| 04:20:39.384 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:39.385 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:39.385 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:39.385 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: pg_ctl -D /var/lib/postgresql/data -l logfile start | |
| 04:20:39.385 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDERR: initdb: warning: enabling "trust" authentication for local connections | |
| 04:20:39.385 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDERR: You can change this by editing pg_hba.conf or using the option -A, or | |
| 04:20:39.385 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDERR: --auth-local and --auth-host, the next time you run initdb. | |
| 04:20:39.386 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:39.648 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: 2020-02-24 04:20:39.647 UTC [46] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432" | |
| 04:20:39.648 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: waiting for server to start....2020-02-24 04:20:39.647 UTC [46] LOG: starting PostgreSQL 12.2 (Debian 12.2-1.pgdg100+1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 8.3.0-6) 8.3.0, 64-bit | |
| 04:20:39.648 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: waiting for server to start....2020-02-24 04:20:39.647 UTC [46] LOG: starting PostgreSQL 12.2 (Debian 12.2-1.pgdg100+1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 8.3.0-6) 8.3.0, 64-bit | |
| 04:20:39.648 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: 2020-02-24 04:20:39.647 UTC [46] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432" | |
| 04:20:39.659 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: 2020-02-24 04:20:39.658 UTC [47] LOG: database system was shut down at 2020-02-24 04:20:37 UTC | |
| 04:20:39.659 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: 2020-02-24 04:20:39.658 UTC [47] LOG: database system was shut down at 2020-02-24 04:20:37 UTC | |
| 04:20:39.663 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: 2020-02-24 04:20:39.662 UTC [46] LOG: database system is ready to accept connections | |
| 04:20:39.670 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: 2020-02-24 04:20:39.662 UTC [46] LOG: database system is ready to accept connections | |
| 04:20:39.704 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: done | |
| 04:20:39.704 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: done | |
| 04:20:39.704 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: server started | |
| 04:20:39.705 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: server started | |
| 04:20:39.800 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: CREATE DATABASE | |
| 04:20:39.800 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: CREATE DATABASE | |
| 04:20:39.801 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:39.801 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:39.801 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: /usr/local/bin/docker-entrypoint.sh: ignoring /docker-entrypoint-initdb.d/* | |
| 04:20:39.801 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:39.803 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: 2020-02-24 04:20:39.802 UTC [46] LOG: received fast shutdown request | |
| 04:20:39.803 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: 2020-02-24 04:20:39.802 UTC [46] LOG: aborting any active transactions | |
| 04:20:39.805 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: waiting for server to shut down...2020-02-24 04:20:39.804 UTC [46] LOG: background worker "logical replication launcher" (PID 53) exited with exit code 1 | |
| 04:20:39.805 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: .2020-02-24 04:20:39.804 UTC [48] LOG: shutting down | |
| 04:20:39.810 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: .2020-02-24 04:20:39.804 UTC [48] LOG: shutting down | |
| 04:20:39.810 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: waiting for server to shut down...2020-02-24 04:20:39.804 UTC [46] LOG: background worker "logical replication launcher" (PID 53) exited with exit code 1 | |
| 04:20:39.811 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: 2020-02-24 04:20:39.802 UTC [46] LOG: aborting any active transactions | |
| 04:20:39.811 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: 2020-02-24 04:20:39.802 UTC [46] LOG: received fast shutdown request | |
| 04:20:39.811 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: 2020-02-24 04:20:39.810 UTC [46] LOG: database system is shut down | |
| 04:20:39.811 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: 2020-02-24 04:20:39.810 UTC [46] LOG: database system is shut down | |
| 04:20:39.811 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:39.811 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: /usr/local/bin/docker-entrypoint.sh: ignoring /docker-entrypoint-initdb.d/* | |
| 04:20:39.811 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:39.812 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:39.905 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: done | |
| 04:20:39.905 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: done | |
| 04:20:39.906 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:39.906 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: server stopped | |
| 04:20:39.906 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: PostgreSQL init process complete; ready for start up. | |
| 04:20:39.906 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: | |
| 04:20:39.906 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:39.906 [main] DEBUG o.t.c.output.WaitingConsumer - STDOUT: server stopped | |
| 04:20:39.906 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: PostgreSQL init process complete; ready for start up. | |
| 04:20:39.907 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDOUT: | |
| 04:20:39.922 [main] DEBUG o.t.c.output.WaitingConsumer - STDERR: 2020-02-24 04:20:39.921 UTC [1] LOG: starting PostgreSQL 12.2 (Debian 12.2-1.pgdg100+1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 8.3.0-6) 8.3.0, 64-bit | |
| 04:20:39.922 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDERR: 2020-02-24 04:20:39.921 UTC [1] LOG: starting PostgreSQL 12.2 (Debian 12.2-1.pgdg100+1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 8.3.0-6) 8.3.0, 64-bit | |
| 04:20:39.922 [main] DEBUG o.t.c.output.WaitingConsumer - STDERR: 2020-02-24 04:20:39.922 UTC [1] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432" | |
| 04:20:39.922 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDERR: 2020-02-24 04:20:39.922 UTC [1] LOG: listening on IPv4 address "0.0.0.0", port 5432 | |
| 04:20:39.923 [main] DEBUG o.t.c.output.WaitingConsumer - STDERR: 2020-02-24 04:20:39.922 UTC [1] LOG: listening on IPv6 address "::", port 5432 | |
| 04:20:39.923 [main] DEBUG o.t.c.output.WaitingConsumer - STDERR: 2020-02-24 04:20:39.922 UTC [1] LOG: listening on IPv4 address "0.0.0.0", port 5432 | |
| 04:20:39.923 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDERR: 2020-02-24 04:20:39.922 UTC [1] LOG: listening on IPv6 address "::", port 5432 | |
| 04:20:39.923 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDERR: 2020-02-24 04:20:39.922 UTC [1] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432" | |
| 04:20:39.934 [main] DEBUG o.t.c.output.WaitingConsumer - STDERR: 2020-02-24 04:20:39.933 UTC [64] LOG: database system was shut down at 2020-02-24 04:20:39 UTC | |
| 04:20:39.934 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDERR: 2020-02-24 04:20:39.933 UTC [64] LOG: database system was shut down at 2020-02-24 04:20:39 UTC | |
| 04:20:39.938 [tc-okhttp-stream-280223635] INFO o.x.t.d.i.j.d.DatabaseContainerExecutor - STDERR: 2020-02-24 04:20:39.937 UTC [1] LOG: database system is ready to accept connections | |
| 04:20:39.944 [main] DEBUG o.t.c.output.WaitingConsumer - STDERR: 2020-02-24 04:20:39.937 UTC [1] LOG: database system is ready to accept connections | |
| 04:20:39.945 [main] INFO o.x.t.d.i.j.XWikiDockerExtension - (*) Building custom XWiki WAR... | |
| 04:20:39.954 [main] INFO o.x.t.d.internal.junit5.WARBuilder - XWiki WAR is not fully built in [target/postgresql-12-pom-jetty-9-chrome/xwiki], (re)building it! | |
| 04:20:44.746 [main] INFO o.x.t.d.internal.junit5.WARBuilder - Using the following extension overrides: [] | |
| 04:20:44.746 [main] INFO o.x.t.d.internal.junit5.WARBuilder - Finding version ... | |
| 04:20:45.487 [main] INFO o.x.t.d.internal.junit5.WARBuilder - Found version = [12.1-SNAPSHOT] | |
| 04:20:45.487 [main] INFO o.x.t.d.internal.junit5.WARBuilder - Resolving distribution dependencies ... | |
| 04:20:45.487 [main] INFO o.x.t.i.maven.MavenResolver - Adding extra JAR to WEB-INF/lib: [org.xwiki.platform:xwiki-platform-mail-send-storage:jar:12.1-SNAPSHOT] | |
| 04:21:07.098 [main] INFO o.x.t.d.internal.junit5.WARBuilder - Copying JAR dependencies ... | |
| ... | |
| 04:21:15.837 [main] INFO o.x.t.d.internal.junit5.WARBuilder - ... Generating XED file for: /root/.m2/repository/org/apache/ivy/ivy/2.5.0/ivy-2.5.0.jar | |
| 04:21:15.845 [main] INFO o.x.t.d.internal.junit5.WARBuilder - Expanding WAR dependencies ... | |
| 04:21:15.845 [main] INFO o.x.t.d.internal.junit5.WARBuilder - ... Unzipping WAR: /root/.m2/repository/org/xwiki/platform/xwiki-platform-web/12.1-SNAPSHOT/xwiki-platform-web-12.1-SNAPSHOT.war | |
| 04:21:17.603 [main] INFO o.x.t.d.internal.junit5.WARBuilder - Copying JDBC driver for database [POSTGRESQL]... | |
| 04:21:17.715 [main] INFO o.x.t.d.internal.junit5.WARBuilder - ... JDBC driver file: /root/.m2/repository/org/postgresql/postgresql/42.2.10/postgresql-42.2.10.jar | |
| 04:21:17.717 [main] INFO o.x.t.d.internal.junit5.WARBuilder - Copying Skin resources ... | |
| 04:21:17.717 [main] INFO o.x.t.d.internal.junit5.WARBuilder - ... Unzipping skin: /root/.m2/repository/org/xwiki/platform/xwiki-platform-flamingo-skin-resources/12.1-SNAPSHOT/xwiki-platform-flamingo-skin-resources-12.1-SNAPSHOT.zip | |
| 04:21:17.814 [main] INFO o.x.t.d.internal.junit5.WARBuilder - Generating configuration files for database [POSTGRESQL]... | |
| 04:21:17.851 [main] INFO o.x.t.d.i.j.ConfigurationFilesGenerator - ... Generating: target/postgresql-12-pom-jetty-9-chrome/xwiki/WEB-INF/hibernate.cfg.xml | |
| 04:21:18.090 [main] INFO o.x.t.d.i.j.ConfigurationFilesGenerator - ... Generating: target/postgresql-12-pom-jetty-9-chrome/xwiki/WEB-INF/xwiki.cfg | |
| 04:21:18.131 [main] INFO o.x.t.d.i.j.ConfigurationFilesGenerator - ... Generating: target/postgresql-12-pom-jetty-9-chrome/xwiki/WEB-INF/xwiki.properties | |
| 04:21:18.182 [main] INFO o.x.t.d.i.j.XWikiDockerExtension - (*) Starting Servlet container [JETTY]... | |
| 04:21:18.194 [main] DEBUG o.t.images.AbstractImagePullPolicy - Using locally available and not pulling image: jetty:9 | |
| 04:21:18.195 [main] INFO o.x.t.d.i.j.AbstractContainerExecutor - Docker image used: [jetty:9] | |
| 04:21:18.196 [main] INFO o.x.t.d.i.junit5.DockerTestUtils - Pulling image [jetty:9] | |
| 04:21:18.197 [main] DEBUG o.t.utility.RegistryAuthLocator - Looking up auth config for image: jetty:9 | |
| 04:21:18.197 [main] DEBUG o.t.utility.RegistryAuthLocator - RegistryAuthLocator has configFile: /root/.docker/config.json (does not exist) and commandPathPrefix: | |
| 04:21:18.199 [main] WARN o.t.utility.RegistryAuthLocator - Failure when attempting to lookup auth config (dockerImageName: jetty:9, configFile: /root/.docker/config.json. Falling back to docker-java default behaviour. Exception message: /root/.docker/config.json (No such file or directory) | |
| 04:21:18.201 [main] DEBUG o.t.d.a.AuthDelegatingDockerClientConfig - Effective auth config [null] | |
| 04:21:19.698 [main] DEBUG o.t.utility.RegistryAuthLocator - Looking up auth config for image: jetty:9 | |
| 04:21:19.698 [main] DEBUG o.t.utility.RegistryAuthLocator - RegistryAuthLocator has configFile: /root/.docker/config.json (does not exist) and commandPathPrefix: | |
| 04:21:19.698 [main] WARN o.t.utility.RegistryAuthLocator - Failure when attempting to lookup auth config (dockerImageName: jetty:9, configFile: /root/.docker/config.json. Falling back to docker-java default behaviour. Exception message: /root/.docker/config.json (No such file or directory) | |
| 04:21:19.698 [main] DEBUG o.t.d.a.AuthDelegatingDockerClientConfig - Effective auth config [null] | |
| 04:21:24.182 [main] TRACE o.testcontainers.utility.AuditLogger - CREATE action with image: jetty:9, containerId: 9a880417fd47d62640982bea27d1874e853680befd8a4d7b1997de3071408426 | |
| 04:21:31.542 [main] TRACE o.testcontainers.utility.AuditLogger - START action with image: null, containerId: 9a880417fd47d62640982bea27d1874e853680befd8a4d7b1997de3071408426 | |
| 04:21:31.556 [main] INFO o.t.c.wait.strategy.HttpWaitStrategy - /funny_diffie: Waiting for 480 seconds for URL: http://172.17.0.1:46637/xwiki/bin/get/Main/WebHome | |
| 04:21:32.353 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:21:32.350:INFO::main: Logging initialized @170ms to org.eclipse.jetty.util.log.StdErrLog | |
| 04:21:32.797 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:21:32.796:INFO:oejs.Server:main: jetty-9.4.26.v20200117; built: 2020-01-17T12:35:33.676Z; git: 7b38981d25d14afb4a12ff1f2596756144edf695; jvm 13.0.2+8 | |
| 04:21:32.825 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:21:32.824:INFO:oejdp.ScanningAppProvider:main: Deployment monitor [file:///var/lib/jetty/webapps/] at interval 1 | |
| 04:21:34.337 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:21:34.336:INFO:oeja.AnnotationConfiguration:main: Scanning elapsed time=119ms | |
| 04:21:35.437 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:21:35.436:INFO:oejs.session:main: DefaultSessionIdManager workerName=node0 | |
| 04:21:35.437 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:21:35.436:INFO:oejs.session:main: No SessionScavenger set, using defaults | |
| 04:21:35.439 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:21:35.438:INFO:oejs.session:main: node0 Scavenging every 660000ms | |
| 04:21:39.249 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDOUT: 2020-02-24 04:21:39,236 [main] INFO iPropertiesConfigurationSource - loading xwiki.properties from file:/var/lib/jetty/webapps/xwiki/WEB-INF/xwiki.properties | |
| 04:21:39.440 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDOUT: 2020-02-24 04:21:39,439 [main] INFO o.x.e.i.ServletEnvironment - Using permanent directory [/var/lib/jetty/xwiki-data] | |
| 04:21:46.219 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: WARNING: An illegal reflective access operation has occurred | |
| 04:21:46.219 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: WARNING: Illegal reflective access by com.thoughtworks.xstream.core.util.Fields (file:/var/lib/jetty/webapps/xwiki/WEB-INF/lib/xstream-1.4.11.1.jar) to field java.util.TreeMap.comparator | |
| 04:21:46.220 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: WARNING: Please consider reporting this to the maintainers of com.thoughtworks.xstream.core.util.Fields | |
| 04:21:46.220 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations | |
| 04:21:46.220 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: WARNING: All illegal access operations will be denied in a future release | |
| 04:21:46.324 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDOUT: 2020-02-24 04:21:46,323 [main] INFO o.x.s.f.i.FilesystemStoreTools - Using filesystem store directory [/var/lib/jetty/xwiki-data/store/file] | |
| 04:21:48.516 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:21:48.514:INFO:oejsh.ContextHandler:main: Started o.e.j.w.WebAppContext@124c278f{XWiki,/xwiki,[file:///var/lib/jetty/webapps/xwiki/, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/bootstrap-3.4.1.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/moment-jdateformatparser-1.0.2.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/jstree-3.3.8.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/momentjs-2.24.0.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/Keypress-2.1.5.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/drawer-2.4.0.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/font-awesome-4.7.0.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/html5shiv-3.7.2.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/iscroll-5.1.3.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/xwiki-platform-job-webjar-12.1-SNAPSHOT.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/bootstrap-daterangepicker-3.0.3.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/respond-1.4.2.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/Eonasdan-bootstrap-datetimepicker-4.17.47.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/jquery-2.2.4.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/xwiki-platform-tree-webjar-12.1-SNAPSHOT.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/selectize.js-0.12.5.jar!/META-INF/resources, jar:file:///var/lib/jetty/webapps/xwiki/WEB-INF/lib/requirejs-2.3.6.jar!/META-INF/resources],AVAILABLE}{/var/lib/jetty/webapps/xwiki} | |
| 04:21:48.544 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:21:48.543:INFO:oejs.AbstractConnector:main: Started ServerConnector@1766b009{HTTP/1.1,[http/1.1]}{0.0.0.0:8080} | |
| 04:21:48.544 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:21:48.543:INFO:oejs.Server:main: Started @16372ms | |
| 04:21:52.782 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDOUT: 2020-02-24 04:21:52,781 [XWiki initialization] INFO .HibernateDataMigrationManager - Checking Hibernate mapping and updating schema if needed for wiki [xwiki] | |
| 04:21:53.212 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDOUT: 2020-02-24 04:21:53,211 [XWiki initialization] INFO .HibernateDataMigrationManager - Storage schema updates and data migrations are enabled | |
| 04:21:53.433 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDOUT: 2020-02-24 04:21:53,432 [XWiki initialization] INFO .HibernateDataMigrationManager - No data migration to apply for wiki [xwiki] currently in version [1138000] | |
| 04:21:53.440 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDOUT: 2020-02-24 04:21:53,439 [XWiki initialization] INFO .HibernateDataMigrationManager - Checking Hibernate mapping and updating schema if needed for wiki [xwiki] | |
| 04:21:59.114 [ducttape-0] TRACE o.t.c.wait.strategy.HttpWaitStrategy - Get response code 200 | |
| 04:21:59.115 [main] INFO o.x.t.d.i.j.XWikiDockerExtension - XWiki ping URL = http://172.17.0.1:46637/xwiki | |
| 04:21:59.115 [main] INFO o.x.t.d.i.j.XWikiDockerExtension - (*) Provision extensions for test... | |
| 04:21:59.279 [main] INFO o.x.t.i.maven.MavenResolver - Adding extra JAR to WEB-INF/lib: [org.xwiki.platform:xwiki-platform-mail-send-storage:jar:12.1-SNAPSHOT] | |
| 04:21:59.285 [main] INFO o.x.t.d.i.junit5.ExtensionInstaller - ...Adding extension [org.xwiki.platform:xwiki-platform-index-tree-macro/12.1-SNAPSHOT] to the list of extensions to provision... | |
| 04:21:59.287 [main] INFO o.x.t.d.i.junit5.ExtensionInstaller - ...Adding extension [org.xwiki.platform:xwiki-platform-tree-macro/12.1-SNAPSHOT] to the list of extensions to provision... | |
| 04:21:59.288 [main] INFO o.x.t.d.i.junit5.ExtensionInstaller - ...Adding extension [org.xwiki.platform:xwiki-platform-icon-fontawesome/12.1-SNAPSHOT] to the list of extensions to provision... | |
| 04:21:59.290 [main] INFO o.x.t.d.i.junit5.ExtensionInstaller - ...Adding extension [org.xwiki.platform:xwiki-platform-icon-ui/12.1-SNAPSHOT] to the list of extensions to provision... | |
| 04:21:59.290 [main] INFO o.x.t.d.i.junit5.ExtensionInstaller - ...Adding extension [org.xwiki.platform:xwiki-platform-mail-ui/12.1-SNAPSHOT] to the list of extensions to provision... | |
| 04:22:00.732 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:22:00.731:INFO:oejshC.xwiki:http://172.17.0.1:46637/xwiki/rest/jobs?jobType=install&async=false: RestletServlet: [Restlet] ServerServlet: component class is null | |
| 04:22:01.883 [tc-okhttp-stream-1867802540] INFO o.x.t.d.i.j.s.ServletContainerExecutor - STDERR: 2020-02-24 04:22:01.882:INFO:oejshC.xwiki:http://172.17.0.1:46637/xwiki/rest/jobs?jobType=install&async=false: RestletServlet: [Restlet] Attaching application: org.xwiki.rest.internal.XWikiRestletJaxRsApplication@5a4dc290 to URI: /xwiki/rest | |
| 04:22:14.731 [main] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - (*) Starting browser [CHROME]... | |
| 04:22:14.737 [main] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - Test resource path mapped: On Host [target/postgresql-12-pom-jetty-9-chrome/test-classes], in Docker: [/tmp/test-resources] | |
| 04:22:14.799 [main] INFO o.t.containers.SeleniumUtils - Selenium API version 3.141.59 detected on classpath | |
| 04:22:14.841 [main] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - Docker image used: [selenium/standalone-chrome-debug:3.141.59] | |
| 04:22:14.841 [main] DEBUG o.t.images.AbstractImagePullPolicy - Using locally available and not pulling image: alpine:3.5 | |
| 04:22:14.841 [main] INFO o.x.t.d.i.junit5.DockerTestUtils - Pulling image [alpine:3.5] | |
| 04:22:14.841 [main] DEBUG o.t.utility.RegistryAuthLocator - Looking up auth config for image: alpine:3.5 | |
| 04:22:14.841 [main] DEBUG o.t.utility.RegistryAuthLocator - RegistryAuthLocator has configFile: /root/.docker/config.json (does not exist) and commandPathPrefix: | |
| 04:22:14.842 [main] WARN o.t.utility.RegistryAuthLocator - Failure when attempting to lookup auth config (dockerImageName: alpine:3.5, configFile: /root/.docker/config.json. Falling back to docker-java default behaviour. Exception message: /root/.docker/config.json (No such file or directory) | |
| 04:22:14.842 [main] DEBUG o.t.d.a.AuthDelegatingDockerClientConfig - Effective auth config [null] | |
| 04:22:16.416 [main] INFO o.t.containers.SeleniumUtils - Selenium API version 3.141.59 detected on classpath | |
| 04:22:16.454 [main] DEBUG o.t.images.AbstractImagePullPolicy - Using locally available and not pulling image: selenium/standalone-chrome-debug:3.141.59 | |
| 04:22:16.454 [main] DEBUG o.t.utility.RegistryAuthLocator - Looking up auth config for image: selenium/standalone-chrome-debug:3.141.59 | |
| 04:22:16.454 [main] DEBUG o.t.utility.RegistryAuthLocator - RegistryAuthLocator has configFile: /root/.docker/config.json (does not exist) and commandPathPrefix: | |
| 04:22:16.454 [main] WARN o.t.utility.RegistryAuthLocator - Failure when attempting to lookup auth config (dockerImageName: selenium/standalone-chrome-debug:3.141.59, configFile: /root/.docker/config.json. Falling back to docker-java default behaviour. Exception message: /root/.docker/config.json (No such file or directory) | |
| 04:22:16.454 [main] DEBUG o.t.d.a.AuthDelegatingDockerClientConfig - Effective auth config [null] | |
| 04:22:16.525 [main] DEBUG o.t.utility.RegistryAuthLocator - Looking up auth config for image: alpine:3.5 | |
| 04:22:16.525 [main] DEBUG o.t.utility.RegistryAuthLocator - RegistryAuthLocator has configFile: /root/.docker/config.json (does not exist) and commandPathPrefix: | |
| 04:22:16.526 [main] WARN o.t.utility.RegistryAuthLocator - Failure when attempting to lookup auth config (dockerImageName: alpine:3.5, configFile: /root/.docker/config.json. Falling back to docker-java default behaviour. Exception message: /root/.docker/config.json (No such file or directory) | |
| 04:22:16.526 [main] DEBUG o.t.d.a.AuthDelegatingDockerClientConfig - Effective auth config [null] | |
| 04:22:16.529 [main] DEBUG o.t.utility.MountableFile - Copying classpath resource(s) from jar:file:/root/.m2/repository/org/testcontainers/testcontainers/1.12.5/testcontainers-1.12.5.jar!/org/testcontainers/utility/ResourceReaper.class to /tmp/.testcontainers-tmp-7716571201326085569 to permit Docker to bind | |
| 04:22:16.529 [main] DEBUG o.t.utility.MountableFile - Copying resource org/testcontainers/utility/ResourceReaper.class from JAR file /root/.m2/repository/org/testcontainers/testcontainers/1.12.5/testcontainers-1.12.5.jar | |
| 04:22:19.557 [main] TRACE o.testcontainers.utility.AuditLogger - START action with image: null, containerId: ab3a2478e859ac8c416dcf2610636f2bee5da8d753cb4075a24655b3f0427218 | |
| 04:22:22.409 [main] TRACE o.testcontainers.utility.AuditLogger - CREATE action with image: selenium/standalone-chrome-debug:3.141.59, containerId: ffd3cecf58a11b5e2f31eba1d9784554febcf829f79df01c80aaa14bd90d4538 | |
| 04:22:25.071 [main] TRACE o.testcontainers.utility.AuditLogger - START action with image: null, containerId: ffd3cecf58a11b5e2f31eba1d9784554febcf829f79df01c80aaa14bd90d4538 | |
| 04:22:25.206 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:25,205 INFO Included extra file "/etc/supervisor/conf.d/selenium-debug.conf" during parsing | |
| 04:22:25.206 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:25,205 INFO Included extra file "/etc/supervisor/conf.d/selenium.conf" during parsing | |
| 04:22:25.209 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:25,208 INFO supervisord started with pid 8 | |
| 04:22:26.215 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:26,211 INFO spawned: 'xvfb' with pid 11 | |
| 04:22:26.216 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:26,213 INFO spawned: 'fluxbox' with pid 12 | |
| 04:22:26.216 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:26,215 INFO spawned: 'vnc' with pid 13 | |
| 04:22:26.223 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:26,220 INFO spawned: 'selenium-standalone' with pid 14 | |
| 04:22:26.670 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 04:22:26.666 INFO [GridLauncherV3.parse] - Selenium server version: 3.141.59, revision: e82be7d358 | |
| 04:22:26.670 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:26,668 INFO success: xvfb entered RUNNING state, process has stayed up for > than 0 seconds (startsecs) | |
| 04:22:26.670 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:26,669 INFO success: fluxbox entered RUNNING state, process has stayed up for > than 0 seconds (startsecs) | |
| 04:22:26.670 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:26,669 INFO success: vnc entered RUNNING state, process has stayed up for > than 0 seconds (startsecs) | |
| 04:22:26.670 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:26,669 INFO success: selenium-standalone entered RUNNING state, process has stayed up for > than 0 seconds (startsecs) | |
| 04:22:26.809 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 04:22:26.808 INFO [GridLauncherV3.lambda$buildLaunchers$3] - Launching a standalone Selenium Server on port 4444 | |
| 04:22:26.886 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 2020-02-24 04:22:26.881:INFO::main: Logging initialized @638ms to org.seleniumhq.jetty9.util.log.StdErrLog | |
| 04:22:27.263 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 04:22:27.258 INFO [WebDriverServlet.<init>] - Initialising WebDriverServlet | |
| 04:22:27.427 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 04:22:27.425 INFO [SeleniumServer.boot] - Selenium Server is up and running on port 4444 | |
| 04:22:27.609 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 04:22:27.608 INFO [ActiveSessionFactory.apply] - Capabilities are: { | |
| 04:22:27.609 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "browserName": "chrome", | |
| 04:22:27.609 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "goog:chromeOptions": { | |
| 04:22:27.609 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "args": [ | |
| 04:22:27.609 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "--whitelisted-ips", | |
| 04:22:27.609 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "--no-sandbox", | |
| 04:22:27.609 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "--disable-extensions" | |
| 04:22:27.609 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: ], | |
| 04:22:27.609 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "extensions": [ | |
| 04:22:27.609 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: ] | |
| 04:22:27.610 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: }, | |
| 04:22:27.610 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "handlesAlerts": true, | |
| 04:22:27.610 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "loggingPrefs": { | |
| 04:22:27.610 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "browser": "ALL" | |
| 04:22:27.610 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: }, | |
| 04:22:27.610 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "unexpectedAlertBehaviour": "ignore", | |
| 04:22:27.610 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: "unhandledPromptBehavior": "ignore" | |
| 04:22:27.610 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: } | |
| 04:22:27.611 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 04:22:27.610 INFO [ActiveSessionFactory.lambda$apply$11] - Matched factory org.openqa.selenium.grid.session.remote.ServicedSession$Factory (provider: org.openqa.selenium.chrome.ChromeDriverService) | |
| 04:22:27.638 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: Starting ChromeDriver 78.0.3904.70 (edb9c9f3de0247fd912a77b7f6cae7447f6d3ad5-refs/branch-heads/3904@{#800}) on port 20307 | |
| 04:22:27.638 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: Only local connections are allowed. | |
| 04:22:27.638 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: Please protect ports used by ChromeDriver and related test frameworks to prevent access by malicious code. | |
| 04:22:27.641 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: [1582518147.639][SEVERE]: bind() failed: Cannot assign requested address (99) | |
| 04:22:28.763 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 04:22:28.761 INFO [ProtocolHandshake.createSession] - Detected dialect: W3C | |
| 04:22:28.815 [tc-okhttp-stream-1795357280] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - STDOUT: 04:22:28.814 INFO [RemoteSession$Factory.lambda$performHandshake$0] - Started new session 7df1ee8c0f3e027fbda645b6ab3f2a8b (org.openqa.selenium.chrome.ChromeDriverService) | |
| 04:22:28.905 [main] INFO o.x.t.d.i.j.b.BrowserContainerExecutor - VNC server address: [vnc://vnc:secret@172.17.0.1:46640] | |
| 04:22:28.936 [main] INFO o.x.t.d.i.j.XWikiDockerExtension - (*) Initialize Test Context... | |
| 04:22:39.705 [main] INFO o.x.t.d.i.j.XWikiDockerExtension - (*) Start VNC container... | |
| 04:22:39.824 [main] DEBUG o.t.images.AbstractImagePullPolicy - Using locally available and not pulling image: quay.io/testcontainers/vnc-recorder:1.1.0 | |
| 04:22:39.824 [main] INFO o.x.t.d.i.junit5.DockerTestUtils - Pulling image [quay.io/testcontainers/vnc-recorder:1.1.0] | |
| 04:22:39.824 [main] DEBUG o.t.utility.RegistryAuthLocator - Looking up auth config for image: quay.io/testcontainers/vnc-recorder:1.1.0 | |
| 04:22:39.824 [main] DEBUG o.t.utility.RegistryAuthLocator - RegistryAuthLocator has configFile: /root/.docker/config.json (does not exist) and commandPathPrefix: | |
| 04:22:39.824 [main] WARN o.t.utility.RegistryAuthLocator - Failure when attempting to lookup auth config (dockerImageName: quay.io/testcontainers/vnc-recorder:1.1.0, configFile: /root/.docker/config.json. Falling back to docker-java default behaviour. Exception message: /root/.docker/config.json (No such file or directory) | |
| 04:22:39.824 [main] DEBUG o.t.d.a.AuthDelegatingDockerClientConfig - Effective auth config [null] | |
| 04:22:42.049 [main] DEBUG o.t.utility.RegistryAuthLocator - Looking up auth config for image: quay.io/testcontainers/vnc-recorder:1.1.0 | |
| 04:22:42.049 [main] DEBUG o.t.utility.RegistryAuthLocator - RegistryAuthLocator has configFile: /root/.docker/config.json (does not exist) and commandPathPrefix: | |
| 04:22:42.050 [main] WARN o.t.utility.RegistryAuthLocator - Failure when attempting to lookup auth config (dockerImageName: quay.io/testcontainers/vnc-recorder:1.1.0, configFile: /root/.docker/config.json. Falling back to docker-java default behaviour. Exception message: /root/.docker/config.json (No such file or directory) | |
| 04:22:42.050 [main] DEBUG o.t.d.a.AuthDelegatingDockerClientConfig - Effective auth config [null] | |
| 04:22:43.565 [main] TRACE o.testcontainers.utility.AuditLogger - CREATE action with image: quay.io/testcontainers/vnc-recorder:1.1.0, containerId: 0817c5d211abb5a6f082b2b5bd2362e526b3cf250083ad75b82317a1099423a1 | |
| 04:22:45.659 [main] TRACE o.testcontainers.utility.AuditLogger - START action with image: null, containerId: 0817c5d211abb5a6f082b2b5bd2362e526b3cf250083ad75b82317a1099423a1 | |
| 04:22:45.779 [main] INFO o.x.t.d.i.j.XWikiDockerExtension - (*) Starting test [verifyMail] | |
| 04:22:58.067 [main] INFO o.x.t.d.i.junit5.DockerTestUtils - Screenshot for test [verifyMail] saved at [/root/workspace/iki_xwiki-platform_stable-12.1.x/xwiki-platform-core/xwiki-platform-mail/xwiki-platform-mail-test/xwiki-platform-mail-test-docker/target/postgresql-12-pom-jetty-9-chrome/screenshots/postgresql-12-default-jetty-9-chrome-org.xwiki.mail.test.ui.MailIT-verifyMail.png]. | |
| 04:22:58.415 [main] INFO o.x.t.d.i.j.XWikiDockerExtension - (*) VNC recording of test has been saved to [target/postgresql-12-pom-jetty-9-chrome/screenshots/postgresql-12-default-jetty-9-chrome-org.xwiki.mail.test.ui.MailIT-verifyMail.flv] | |
| 04:22:58.415 [main] INFO o.x.t.d.i.j.XWikiDockerExtension - Jenkins Agent: [Jenkins SSH Slave a4-009cqltvsdqg2] | |
| 04:22:58.421 [main] INFO o.x.t.d.i.j.XWikiDockerExtension - (*) Stopping test [verifyMail] | |
| 04:22:58.425 [main] TRACE o.t.utility.ResourceReaper - Stopping container: 0817c5d211abb5a6f082b2b5bd2362e526b3cf250083ad75b82317a1099423a1 | |
| 04:23:00.880 [main] TRACE o.testcontainers.utility.AuditLogger - KILL action with image: null, containerId: 0817c5d211abb5a6f082b2b5bd2362e526b3cf250083ad75b82317a1099423a1 | |
| 04:23:00.880 [main] TRACE o.t.utility.ResourceReaper - Stopped container: quay.io/testcontainers/vnc-recorder:1.1.0 | |
| 04:23:00.883 [main] TRACE o.t.utility.ResourceReaper - Removing container: 0817c5d211abb5a6f082b2b5bd2362e526b3cf250083ad75b82317a1099423a1 | |
| 04:23:01.012 [main] DEBUG o.t.utility.ResourceReaper - Removed container and associated volume(s): quay.io/testcontainers/vnc-recorder:1.1.0 |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment