2024-11-21 02:22:16,077 INFO    pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpwu7a9zp8
2024-11-21 02:22:16,078 INFO    Running Gatling simulation with command: mvn gatling:test --batch-mode  -Dgatling.reportsOnly="/results/gatling/benchmark-130/loadtest-20241121001157101" -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/tmpwu7a9zp8/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/tmpwu7a9zp8/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 ---
02:22:17,679 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8
02:22:17,681 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service.
02:22:17,681 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
02:22:17,681 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
02:22:17,686 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
02:22:17,687 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
02:22:17,693 |-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
02:22:17,693 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
02:22:17,694 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
02:22:17,694 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
02:22:17,697 |-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]
02:22:17,700 |-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
02:22:17,739 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - <level> element is deprecated. Near [level] on line 12
02:22:17,739 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within <logger> or <root> elements instead.
02:22:17,774 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE]
02:22:17,774 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
02:22:17,780 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
02:22:17,799 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN
02:22:17,800 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT]
02:22:17,800 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration.
02:22:17,800 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point
02:22:17,800 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 106 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY

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

================================================================================
---- Global Information --------------------------------------------------------
> request count                                    3998616 (OK=2904320 KO=1094296)
> min response time                                     32 (OK=33     KO=32    )
> max response time                                  86830 (OK=86830  KO=60829 )
> mean response time                                 10564 (OK=11367  KO=8433  )
> std deviation                                      13331 (OK=12321  KO=15501 )
> response time 50th percentile                       5789 (OK=7578   KO=39    )
> response time 75th percentile                      16368 (OK=17934  KO=11192 )
> response time 95th percentile                      38654 (OK=36888  KO=60000 )
> response time 99th percentile                      60000 (OK=51322  KO=60000 )
> mean requests/sec                                1110.73 (OK=806.76 KO=303.97)
---- Response Time Distribution ------------------------------------------------
> t < 800 ms                                        760184 ( 19.01%)
> 800 ms <= t < 1200 ms                              52700 (  1.32%)
> t >= 1200 ms                                     2091436 (  52.3%)
> failed                                           1094296 ( 27.37%)
---- Errors --------------------------------------------------------------------
> status.find.in([200, 209], 304), found 503                     540042 (36.27%)
> RootUserinfo: No attribute named 'access_token' is defined     170052 (11.42%)
> status.find.is(302), but actually found 503                    139063 ( 9.34%)
> status.find.in([200, 209], 304), found 404                     106819 ( 7.17%)
> CreatePolicy: No attribute named 'oauth2_uuId' is defined       68266 ( 4.59%)
> status.find.in([200, 209], 304), found 500                      65864 ( 4.42%)
> userinfo: No attribute named 'access_token' is defined          59597 ( 4.00%)
> Request timeout to openam-perf-benchmark.forgeblocks.com/34.8.  57098 ( 3.83%)
9.233:443 after 60000 ms
> headerRegex((Location,(?<=code=)(.+?)(?=&))).find.exists, foun  53802 ( 3.61%)
d nothing
> status.find.is(200), but actually found 503                     48981 ( 3.29%)
> tokenUserinfo: No attribute named 'access_token' is defined     25537 ( 1.72%)
> status.find.in([200, 209], 304), found 502                      20102 ( 1.35%)
> deleteOrganization: No attribute named 'organization_id' is de  20062 ( 1.35%)
fined 
> queryOrganization: No attribute named 'organization_id' is def  20062 ( 1.35%)
ined 
> status.find.is(200), but actually found 502                     17656 ( 1.19%)
> createOrganization: No attribute named 'access_token' is defin  16820 ( 1.13%)
ed 
> status.find.is(302), but actually found 502                     16512 ( 1.11%)
> status.find.in([200, 209], 304), found 401                      12388 ( 0.83%)
> status.find.is(200), but actually found 401                      9578 ( 0.64%)
>  UI AM revoke1: No attribute named 'access_token' is defined     5161 ( 0.35%)
>  UI AM revoke2: No attribute named 'access_token_2' is defined   5040 ( 0.34%)
 
>  UI AM endsession: No attribute named 'id_token_2' is defined    3996 ( 0.27%)
> headerRegex((location,#id_token=(.*))).find.exists, found noth   2998 ( 0.20%)
ing
> status.find.in([200, 209], 304), found 403                       1440 ( 0.10%)
> status.find.in([200, 209], 304), found 400                       1151 ( 0.08%)
> jsonPath($.result[1].uuid).find.exists, found nothing             462 ( 0.03%)
> status.find.in([200, 209], 304), found 409                        333 ( 0.02%)
> j.i.IOException: Premature close                                    7 ( 0.00%)
================================================================================

Reports generated, please open the following file: file:///results/gatling/benchmark-130/loadtest-20241121001157101/index.html
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  25.837 s
[INFO] Finished at: 2024-11-21T02:22:42Z
[INFO] ------------------------------------------------------------------------
2024-11-21 02:22:42,711 INFO    Gatling simulation run ended successfully
2024-11-21 02:22:42,711 INFO    Creating archive benchmark-130.tar.gz with report files
2024-11-21 02:22:43,426 INFO    Archive /results/gatling/benchmark-130/benchmark-130.tar.gz successfully created