2024-11-18 23:28:37,862 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp7y6ks28i 2024-11-18 23:28:37,863 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/benchmark-140/loadtest-20241118202031368" -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" [INFO] Scanning for projects... [INFO] [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- [INFO] Building pyrock-gatling-simulation 3.12.0 [INFO] from pom.xml [INFO] --------------------------------[ jar ]--------------------------------- [INFO] [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> [INFO] [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- [INFO] skip non existing resourceDirectory /tmp/tmp7y6ks28i/src/main/resources [INFO] [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- [INFO] No sources to compile [INFO] [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- [INFO] skip non existing resourceDirectory /tmp/tmp7y6ks28i/src/test/resources [INFO] [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- [INFO] No sources to compile [INFO] [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- [INFO] compile in 0.0 s [INFO] No sources to compile [INFO] [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< [INFO] [INFO] [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 23:28:39,473 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 23:28:39,474 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 23:28:39,474 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 23:28:39,475 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 23:28:39,480 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 23:28:39,480 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 23:28:39,487 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 6 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY 23:28:39,487 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 23:28:39,487 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 23:28:39,487 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 23:28:39,490 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.m2/repository/io/gatling/gatling-maven-plugin/4.9.6/gatling-maven-plugin-4.9.6.jar!/logback.xml] 23:28:39,494 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@240237d2 - URL [jar:file:/home/jenkins/.m2/repository/io/gatling/gatling-maven-plugin/4.9.6/gatling-maven-plugin-4.9.6.jar!/logback.xml] is not of type file 23:28:39,533 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 23:28:39,533 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 23:28:39,567 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 23:28:39,567 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 23:28:39,573 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 23:28:39,593 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 23:28:39,593 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 23:28:39,593 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 23:28:39,594 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 23:28:39,594 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 107 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY Parsing log file(s)... Parsing log file(s) done in 46s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 6982759 (OK=6982435 KO=324 ) > min response time 36 (OK=36 KO=37 ) > max response time 60000 (OK=52623 KO=60000 ) > mean response time 76 (OK=76 KO=289 ) > std deviation 56 (OK=51 KO=3368 ) > response time 50th percentile 61 (OK=61 KO=67 ) > response time 75th percentile 92 (OK=92 KO=84 ) > response time 95th percentile 157 (OK=157 KO=114 ) > response time 99th percentile 229 (OK=229 KO=142 ) > mean requests/sec 1939.66 (OK=1939.57 KO=0.09 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 6982285 ( 99.99%) > 800 ms <= t < 1200 ms 101 ( 0%) > t >= 1200 ms 49 ( 0%) > failed 324 ( 0%) ---- Errors -------------------------------------------------------------------- > tokenUserinfo: No attribute named 'access_token' is defined 26252 (98.77%) > status.find.in([200, 209], 304), found 409 198 ( 0.74%) > status.find.in([200, 209], 304), found 404 113 ( 0.43%) > status.find.is(200), but actually found 502 5 ( 0.02%) > status.find.in([200, 209], 304), found 502 2 ( 0.01%) > status.find.is(200), but actually found 403 1 ( 0.00%) > headerRegex((location,#id_token=(.*))).find.exists, found noth 1 ( 0.00%) ing > status.find.is(302), but actually found 502 1 ( 0.00%) > UI AM revoke2: No attribute named 'access_token_2' is defined 1 ( 0.00%) > status.find.in([200, 209], 304), found 401 1 ( 0.00%) > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 1 ( 0.00%) r 10000ms > userinfo: No attribute named 'access_token' is defined 1 ( 0.00%) > UI AM revoke1: No attribute named 'access_token' is defined 1 ( 0.00%) > UI AM endsession: No attribute named 'id_token_2' is defined 1 ( 0.00%) > Request timeout to openam-perf-benchmark.forgeblocks.com/34.8. 1 ( 0.00%) 9.233:443 after 60000 ms ================================================================================ Reports generated, please open the following file: file:///results/gatling/benchmark-140/loadtest-20241118202031368/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 49.236 s [INFO] Finished at: 2024-11-18T23:29:27Z [INFO] ------------------------------------------------------------------------ 2024-11-18 23:29:27,896 INFO Gatling simulation run ended successfully 2024-11-18 23:29:27,896 INFO Creating archive benchmark-140.tar.gz with report files 2024-11-18 23:29:28,553 INFO Archive /results/gatling/benchmark-140/benchmark-140.tar.gz successfully created