[INFO] Scanning for projects...
[WARNING] The project org.osiam.tests:performance-tests:jar:3.0-SNAPSHOT uses prerequisites which is only intended for maven-plugin projects but not for non maven-plugin projects. For such purposes you should use the maven-enforcer-plugin. See https://maven.apache.org/enforcer/enforcer-rules/requireMavenVersion.html
[INFO] 
[INFO] ------------------------------------------------------------------------
[INFO] Building OSIAM performance tests 3.0-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO] 
[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ performance-tests ---
[INFO] Deleting /home/user/performance-tests-master/target
[INFO] 
[INFO] --- docker-maven-plugin:2.10.6:clean (clean-docker-images) @ performance-tests ---
[INFO] Docker version 17.06.1-ce
[INFO] reading /home/user/performance-tests-master/src/main/docker/tomcat/conf.yml
[INFO] reading /home/user/performance-tests-master/src/main/docker/postgres/conf.yml
[INFO] reading /home/user/performance-tests-master/src/main/docker/mysql/conf.yml
[INFO] Stopping tomcat
[INFO] Stopping tomcat
[INFO] Cleaning tomcat
[INFO] Removing container 3bb6fc841ab65616565a8131a24b507c0147290262d082521d271108981b7f4a
[INFO] Removing image sha256:29a98efd0448c80852e66c1140c1a496d2915e1f2578825c0d51189dfd6a639a
[INFO] Stopping mysql
[INFO] Stopping mysql
[INFO] Cleaning mysql
[INFO] Removing container d669b53fae215d459e08168557da46195f75967ca930741a16523d5651398b7b
[INFO] Removing image sha256:4ab3f8a1a18915afcf3a78b8d761dab16af1a64bf321bd481b10d88d00207ec5
[INFO] Not including postgres, filtered out
[INFO] 
[INFO] --- copy-rename-maven-plugin:1.0.1:copy (copy-properties) @ performance-tests ---
[INFO] Copied /home/user/performance-tests-master/src/main/resources/performance-tests_mysql.properties to /home/user/performance-tests-master/src/main/resources/performance-tests.properties
[INFO] 
[INFO] --- maven-dependency-plugin:2.8:copy (download-osiam) @ performance-tests ---
[INFO] Configured Artifact: org.osiam:osiam:latest-SNAPSHOT:war
[INFO] Copying osiam-latest-SNAPSHOT.war to /home/user/performance-tests-master/target/dependency/osiam.war
[INFO] 
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ performance-tests ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 8 resources
[INFO] Copying 1 resource
[INFO] 
[INFO] --- maven-compiler-plugin:3.5.1:compile (default-compile) @ performance-tests ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 8 source files to /home/user/performance-tests-master/target/classes
[INFO] /home/user/performance-tests-master/src/main/java/org/osiam/tests/performance/tools/TestDataCreation.java: /home/user/performance-tests-master/src/main/java/org/osiam/tests/performance/tools/TestDataCreation.java uses or overrides a deprecated API.
[INFO] /home/user/performance-tests-master/src/main/java/org/osiam/tests/performance/tools/TestDataCreation.java: Recompile with -Xlint:deprecation for details.
[INFO] 
[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ performance-tests ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/user/performance-tests-master/src/test/resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.5.1:testCompile (default-testCompile) @ performance-tests ---
[INFO] No sources to compile
[INFO] 
[INFO] --- maven-surefire-plugin:2.19.1:test (default-test) @ performance-tests ---
[INFO] Tests are skipped.
[INFO] 
[INFO] --- maven-jar-plugin:3.0.2:jar (default-jar) @ performance-tests ---
[INFO] Building jar: /home/user/performance-tests-master/target/jmeter/lib/junit/performance-tests-3.0-SNAPSHOT.jar
[INFO] 
[INFO] --- maven-dependency-plugin:2.8:copy-dependencies (copy-dependencies) @ performance-tests ---
[INFO] Copying commons-io-2.5.jar to /home/user/performance-tests-master/target/jmeter/lib/commons-io-2.5.jar
...
[INFO] Copying stax-api-1.0.1.jar to /home/user/performance-tests-master/target/jmeter/lib/stax-api-1.0.1.jar
[INFO] 
[INFO] --- docker-maven-plugin:2.10.6:start (start-docker-container) @ performance-tests ---
[INFO] Docker version 17.06.1-ce
[INFO] reading /home/user/performance-tests-master/src/main/docker/tomcat/conf.yml
[INFO] reading /home/user/performance-tests-master/src/main/docker/postgres/conf.yml
[INFO] reading /home/user/performance-tests-master/src/main/docker/mysql/conf.yml
[INFO] Not including postgres, filtered out
[INFO] Starting mysql
[INFO] Image does not exist, so building it
[INFO] Preparing mysql
[INFO] Building mysql (root/performance-tests_mysql)
[INFO]  - no cache: true
[INFO]  - remove intermediate images: true
[INFO]  - quiet: true
[INFO] No existing container so creating and starting new one
[INFO]  - links []
[INFO]  - port 23306->3306
[INFO]  - volumes {}
[INFO]  - env {}
[INFO] Sleeping for 1000ms
[INFO] Starting tomcat
[INFO] Image does not exist, so building it
[INFO] Preparing tomcat
[INFO]  - add /home/user/performance-tests-master/target/dependency/osiam.war
[INFO] Building tomcat (performance-tests_tomcat-mysql)
[INFO]  - no cache: true
[INFO]  - remove intermediate images: true
[INFO]  - quiet: true
[INFO] No existing container so creating and starting new one
[INFO]  - links [mysql]
[INFO]  - port 8280->8280
[INFO]  - volumes {}
[INFO]  - env {}
[INFO] Sleeping for 1000ms
[INFO] Pinging http://localhost:8280/manager/text/list for pattern "osiam:running"
[INFO] STDERR: 01-Sep-2017 14:47:44.109 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version:        Apache Tomcat/8.5.20
[INFO] STDERR: 01-Sep-2017 14:47:44.117 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built:          Aug 2 2017 21:35:49 UTC
[INFO] STDERR: 01-Sep-2017 14:47:44.117 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server number:         8.5.20.0
[INFO] STDERR: 01-Sep-2017 14:47:44.117 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name:               Linux
[INFO] STDERR: 01-Sep-2017 14:47:44.117 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version:            3.10.0-327.36.1.el7.x86_64
[INFO] STDERR: 01-Sep-2017 14:47:44.118 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture:          amd64
[INFO] STDERR: 01-Sep-2017 14:47:44.118 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home:             /usr/lib/jvm/java-8-openjdk-amd64/jre
[INFO] STDERR: 01-Sep-2017 14:47:44.118 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version:           1.8.0_141-8u141-b15-1~deb9u1-b15
[INFO] STDERR: 01-Sep-2017 14:47:44.118 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor:            Oracle Corporation
[INFO] STDERR: 01-Sep-2017 14:47:44.118 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE:         /usr/local/tomcat
[INFO] STDERR: 01-Sep-2017 14:47:44.118 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME:         /usr/local/tomcat
[INFO] STDERR: 01-Sep-2017 14:47:44.118 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=/usr/local/tomcat/conf/logging.properties
[INFO] STDERR: 01-Sep-2017 14:47:44.118 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
[INFO] STDERR: 01-Sep-2017 14:47:44.118 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048
[INFO] STDERR: 01-Sep-2017 14:47:44.118 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources
[INFO] STDERR: 01-Sep-2017 14:47:44.119 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=/usr/local/tomcat
[INFO] STDERR: 01-Sep-2017 14:47:44.119 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=/usr/local/tomcat
[INFO] STDERR: 01-Sep-2017 14:47:44.119 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=/usr/local/tomcat/temp
[INFO] STDERR: 01-Sep-2017 14:47:44.121 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded APR based Apache Tomcat Native library [1.2.12] using APR version [1.5.2].
[INFO] STDERR: 01-Sep-2017 14:47:44.121 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
[INFO] STDERR: 01-Sep-2017 14:47:44.121 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true]
[INFO] STDERR: 01-Sep-2017 14:47:44.125 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.1.0f  25 May 2017]
[INFO] STDERR: 01-Sep-2017 14:47:44.526 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8280"]
[INFO] STDERR: 01-Sep-2017 14:47:44.542 INFO [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read
[INFO] STDERR: 01-Sep-2017 14:47:44.545 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["ajp-nio-8009"]
[INFO] STDERR: 01-Sep-2017 14:47:44.546 INFO [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read
[INFO] STDERR: 01-Sep-2017 14:47:44.548 INFO [main] org.apache.catalina.startup.Catalina.load Initialization processed in 4335 ms
[INFO] STDERR: 01-Sep-2017 14:47:44.573 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina]
[INFO] STDERR: 01-Sep-2017 14:47:44.573 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet Engine: Apache Tomcat/8.5.20
[INFO] STDERR: 01-Sep-2017 14:47:44.589 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDescriptor Deploying configuration descriptor [/usr/local/tomcat/conf/Catalina/localhost/osiam.xml]
[INFO] STDERR: 01-Sep-2017 14:47:48.434 INFO [localhost-startStop-1] org.apache.jasper.servlet.TldScanner.scanJars 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.
[INFO] STDOUT: 2017-09-01 14:47:49.408  INFO 1 --- [ost-startStop-1] org.osiam.Osiam                          : Starting Osiam on b0083d52d80b with PID 1 (/usr/local/tomcat/webapps/osiam/WEB-INF/classes started by root in /usr/local/tomcat)
[INFO] STDOUT: 2017-09-01 14:47:49.424  INFO 1 --- [ost-startStop-1] org.osiam.Osiam                          : No active profile set, falling back to default profiles: default
[INFO] STDOUT: 2017-09-01 14:47:49.861  INFO 1 --- [ost-startStop-1] org.osiam.OsiamHome                      : osiam.home = /var/lib/osiam
[INFO] STDOUT: 2017-09-01 14:47:57.360  INFO 1 --- [ost-startStop-1] org.osiam.ExampleData                    : Created OAuth client 'example-client' with secret 'secret'
[INFO] STDOUT: 2017-09-01 14:47:58.159  INFO 1 --- [ost-startStop-1] org.osiam.ExampleData                    : Created admin user 'admin' with password 'koala' and id 'bfb32739-50e1-4047-b557-06c879a30d1e'
[INFO] STDOUT: 2017-09-01 14:47:58.792  WARN 1 --- [ost-startStop-1] figurationPropertiesBindingPostProcessor : The @ConfigurationProperties bean class org.osiam.scim.extension.ExtensionsConfiguration contains validation constraints but had not been annotated with @Validated.
[INFO] STDOUT: 2017-09-01 14:47:58.793  WARN 1 --- [ost-startStop-1] figurationPropertiesBindingPostProcessor : The @ConfigurationProperties bean class org.osiam.scim.extension.ExtensionsConfiguration contains validation constraints but had not been annotated with @Validated.
[INFO] STDOUT: 2017-09-01 14:47:58.793  WARN 1 --- [ost-startStop-1] figurationPropertiesBindingPostProcessor : The @ConfigurationProperties bean class org.osiam.scim.extension.ExtensionsConfiguration contains validation constraints but had not been annotated with @Validated.
[INFO] STDOUT: 2017-09-01 14:48:02.244  INFO 1 --- [ost-startStop-1] org.osiam.Osiam                          : Started Osiam in 13.565 seconds (JVM running for 23.937)
[INFO] STDERR: 01-Sep-2017 14:48:02.522 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDescriptor Deployment of configuration descriptor [/usr/local/tomcat/conf/Catalina/localhost/osiam.xml] has finished in [17,933] ms
[INFO] STDERR: 01-Sep-2017 14:48:02.523 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/usr/local/tomcat/webapps/ROOT]
[INFO] STDERR: 01-Sep-2017 14:48:02.535 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/usr/local/tomcat/webapps/ROOT] has finished in [12] ms
[INFO] STDERR: 01-Sep-2017 14:48:02.536 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/usr/local/tomcat/webapps/docs]
[INFO] STDERR: 01-Sep-2017 14:48:02.544 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/usr/local/tomcat/webapps/docs] has finished in [8] ms
[INFO] STDERR: 01-Sep-2017 14:48:02.544 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/usr/local/tomcat/webapps/examples]
[INFO] STDERR: 01-Sep-2017 14:48:02.739 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/usr/local/tomcat/webapps/examples] has finished in [195] ms
[INFO] STDERR: 01-Sep-2017 14:48:02.739 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/usr/local/tomcat/webapps/host-manager]
[INFO] STDERR: 01-Sep-2017 14:48:02.759 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/usr/local/tomcat/webapps/host-manager] has finished in [20] ms
[INFO] STDERR: 01-Sep-2017 14:48:02.760 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/usr/local/tomcat/webapps/manager]
[INFO] STDERR: 01-Sep-2017 14:48:02.772 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/usr/local/tomcat/webapps/manager] has finished in [12] ms
[INFO] STDERR: 01-Sep-2017 14:48:02.776 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8280"]
[INFO] STDERR: 01-Sep-2017 14:48:02.783 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["ajp-nio-8009"]
[INFO] STDERR: 01-Sep-2017 14:48:02.785 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in 18236 ms
[INFO] Not including postgres, filtered out
[INFO] 
[INFO] >>> jmeter-maven-plugin:2.0.3:jmeter (jmeter-tests) > :configure @ performance-tests >>>
[INFO] 
[INFO] --- jmeter-maven-plugin:2.0.3:configure (configure) @ performance-tests ---
[INFO] -------------------------------------------------------
[INFO]  Configuring JMeter...
[INFO] -------------------------------------------------------
[INFO] 
[INFO] <<< jmeter-maven-plugin:2.0.3:jmeter (jmeter-tests) < :configure @ performance-tests <<<
[INFO] 
[INFO] 
[INFO] --- jmeter-maven-plugin:2.0.3:jmeter (jmeter-tests) @ performance-tests ---
[INFO]  
[INFO] -------------------------------------------------------
[INFO]  P E R F O R M A N C E    T E S T S
[INFO] -------------------------------------------------------
[INFO] Invalid value detected for <postTestPauseInSeconds>.  Setting pause to 0...
[INFO]  
[INFO]  
[INFO] Executing test: OSIAM Performance Tests.jmx
[INFO] Writing log file to: /home/user/performance-tests-master/target/jmeter/logs/OSIAM Performance Tests.jmx.log
[INFO] Creating summariser <summary>
[INFO] Created the tree successfully using /home/user/performance-tests-master/target/jmeter/testFiles/OSIAM Performance Tests.jmx
[INFO] Starting the test @ Fri Sep 01 14:48:24 UTC 2017 (1504277304384)
[INFO] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[INFO] SLF4J: Class path contains multiple SLF4J bindings.
[INFO] SLF4J: Found binding in [jar:file:/home/user/performance-tests-master/target/jmeter/lib/logback-classic-1.1.7.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[INFO] SLF4J: Found binding in [jar:file:/home/user/performance-tests-master/target/jmeter/lib/ext/ApacheJMeter_core-3.0.jar!/org/slf4j/impl/StaticLoggerBinder.class]
[INFO] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
[INFO] SLF4J: Actual binding is of type [ch.qos.logback.classic.util.ContextSelectorStaticBinder]
[INFO] [INFO] 2017-09-01 14:48:25,744 org.osiam.tests.performance.PerformanceTestContext - Setting up database
[INFO] [INFO] 2017-09-01 14:48:25,898 org.osiam.tests.performance.tools.TestDataCreation - Start database setup
[INFO] [ERROR] 2017-09-01 14:48:27,941 org.osiam.tests.performance.tools.TestDataCreation - java.sql.SQLSyntaxErrorException: Unknown table 'osiam_client' in information_schema
[INFO] [INFO] 2017-09-01 14:48:27,942 org.osiam.tests.performance.tools.TestDataCreation - Finished database setup
[INFO] [INFO] 2017-09-01 14:48:27,942 org.osiam.tests.performance.PerformanceTestContext - Setting up Connector
[INFO] [INFO] 2017-09-01 14:48:29,426 org.osiam.tests.performance.PerformanceTestContext - Retrieving access token
[INFO] summary =      0 in 00:00:00 = ******/s Avg:     0 Min: 9223372036854775807 Max: -9223372036854775808 Err:     0 (0.00%)
[INFO] Tidying up ...    @ Fri Sep 01 14:48:30 UTC 2017 (1504277310301)
[INFO] ... end of run
[INFO] Completed Test: OSIAM Performance Tests.jmx
[INFO] 
[INFO] --- docker-maven-plugin:2.10.6:stop (stop-docker-container) @ performance-tests ---
[INFO] Docker version 17.06.1-ce
[INFO] reading /home/user/performance-tests-master/src/main/docker/tomcat/conf.yml
[INFO] reading /home/user/performance-tests-master/src/main/docker/postgres/conf.yml
[INFO] reading /home/user/performance-tests-master/src/main/docker/mysql/conf.yml
[INFO] Stopping tomcat
[INFO] Stopping container [/performance-tests_tomcat]
[INFO] Stopping mysql
[INFO] Stopping container [/performance-tests_mysql, /performance-tests_tomcat/mysql]
[INFO] Not including postgres, filtered out
[INFO] 
[INFO] --- xml-maven-plugin:1.0.1:transform (default) @ performance-tests ---
Warning: org.apache.xerces.parsers.SAXParser: Feature 'http://javax.xml.XMLConstants/feature/secure-processing' is not recognized.
Warning: org.apache.xerces.parsers.SAXParser: Property 'http://javax.xml.XMLConstants/property/accessExternalDTD' is not recognized.
Warning: org.apache.xerces.parsers.SAXParser: Property 'http://www.oracle.com/xml/jaxp/properties/entityExpansionLimit' is not recognized.
[INFO] Transforming file: /home/user/performance-tests-master/target/jmeter/results/20170901-OSIAM Performance Tests.jtl
[INFO] Transformed 1 file(s).
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 01:40 min
[INFO] Finished at: 2017-09-01T14:48:35Z
[INFO] Final Memory: 39M/188M
[INFO] ------------------------------------------------------------------------
[INFO] Shutdown detected, destroying JMeter process...