2024-11-18 23:29:52,882 INFO    pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp_74rmapl
2024-11-18 23:29:52,883 INFO    Running Gatling simulation with command: mvn gatling:test --batch-mode  -Dgatling.reportsOnly="/results/gatling/benchmark-150/loadtest-20241118212111864" -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/tmp_74rmapl/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/tmp_74rmapl/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:29:54,527 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8
23:29:54,528 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service.
23:29:54,528 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
23:29:54,529 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
23:29:54,534 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
23:29:54,534 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
23:29:54,541 |-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:29:54,541 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
23:29:54,542 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
23:29:54,542 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
23:29:54,545 |-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:29:54,548 |-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:29:54,588 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - <level> element is deprecated. Near [level] on line 12
23:29:54,588 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within <logger> or <root> elements instead.
23:29:54,622 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE]
23:29:54,623 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
23:29:54,629 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
23:29:54,649 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN
23:29:54,649 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT]
23:29:54,649 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration.
23:29:54,650 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point
23:29:54,650 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 108 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY

Parsing log file(s)...
Parsing log file(s) done in 28s.
Generating reports...

================================================================================
---- Global Information --------------------------------------------------------
> request count                                    4857255 (OK=4255379 KO=601876)
> min response time                                     36 (OK=36     KO=36    )
> max response time                                  60055 (OK=59998  KO=60055 )
> mean response time                                  3246 (OK=2904   KO=5662  )
> std deviation                                       8442 (OK=7684   KO=12289 )
> response time 50th percentile                         63 (OK=62     KO=86    )
> response time 75th percentile                        270 (OK=183    KO=6205  )
> response time 95th percentile                      21945 (OK=20770  KO=30950 )
> response time 99th percentile                      42901 (OK=38476  KO=60000 )
> mean requests/sec                                1349.24 (OK=1182.05 KO=167.19)
---- Response Time Distribution ------------------------------------------------
> t < 800 ms                                       3325763 ( 68.47%)
> 800 ms <= t < 1200 ms                              46429 (  0.96%)
> t >= 1200 ms                                      883187 ( 18.18%)
> failed                                            601876 ( 12.39%)
---- Errors --------------------------------------------------------------------
> status.find.is(200), but actually found 401                    189151 (24.65%)
> status.find.in([200, 209], 304), found 503                     187976 (24.50%)
> RootUserinfo: No attribute named 'access_token' is defined      57780 ( 7.53%)
> status.find.is(302), but actually found 503                     49012 ( 6.39%)
> status.find.in([200, 209], 304), found 404                      44104 ( 5.75%)
> CreatePolicy: No attribute named 'oauth2_uuId' is defined       26849 ( 3.50%)
> userinfo: No attribute named 'access_token' is defined          24846 ( 3.24%)
> status.find.in([200, 209], 304), found 500                      23069 ( 3.01%)
> status.find.in([200, 209], 304), found 502                      21584 ( 2.81%)
> tokenUserinfo: No attribute named 'access_token' is defined     21393 ( 2.79%)
> status.find.is(200), but actually found 503                     20092 ( 2.62%)
> headerRegex((Location,(?<=code=)(.+?)(?=&))).find.exists, foun  16627 ( 2.17%)
d nothing
> Request timeout to openam-perf-benchmark.forgeblocks.com/34.8.  15799 ( 2.06%)
9.233:443 after 60000 ms
> status.find.is(200), but actually found 502                     14184 ( 1.85%)
> status.find.is(302), but actually found 502                      7994 ( 1.04%)
> deleteOrganization: No attribute named 'organization_id' is de   7424 ( 0.97%)
fined 
> queryOrganization: No attribute named 'organization_id' is def   7424 ( 0.97%)
ined 
> status.find.in([200, 209], 304), found 401                       6817 ( 0.89%)
> createOrganization: No attribute named 'access_token' is defin   5787 ( 0.75%)
ed 
>  UI AM revoke1: No attribute named 'access_token' is defined     5106 ( 0.67%)
>  UI AM revoke2: No attribute named 'access_token_2' is defined   4765 ( 0.62%)
 
>  UI AM endsession: No attribute named 'id_token_2' is defined    4124 ( 0.54%)
> headerRegex((location,#id_token=(.*))).find.exists, found noth   3409 ( 0.44%)
ing
> status.find.in([200, 209], 304), found 403                        887 ( 0.12%)
> status.find.in([200, 209], 304), found 400                        596 ( 0.08%)
> jsonPath($.result[1].uuid).find.exists, found nothing             367 ( 0.05%)
> status.find.in([200, 209], 304), found 409                        167 ( 0.02%)
> status.find.is(302), but actually found 400                        40 ( 0.01%)
> j.i.IOException: Premature close                                    1 ( 0.00%)
================================================================================

Reports generated, please open the following file: file:///results/gatling/benchmark-150/loadtest-20241118212111864/index.html
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  31.329 s
[INFO] Finished at: 2024-11-18T23:30:24Z
[INFO] ------------------------------------------------------------------------
2024-11-18 23:30:24,994 INFO    Gatling simulation run ended successfully
2024-11-18 23:30:24,994 INFO    Creating archive benchmark-150.tar.gz with report files
2024-11-18 23:30:25,580 INFO    Archive /results/gatling/benchmark-150/benchmark-150.tar.gz successfully created