2025-09-19 02:36:13,334 INFO    pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmprqqkslb7
2025-09-19 02:36:13,336 INFO    Running Gatling simulation with command: mvn gatling:test --batch-mode  -Dgatling.reportsOnly="/results/gatling/benchmark-500/loadtest-20250919002032997" -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/tmprqqkslb7/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/tmprqqkslb7/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:36:15,917 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8
02:36:15,919 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service.
02:36:15,919 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator
02:36:15,920 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator
02:36:15,927 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo]
02:36:15,927 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo]
02:36:15,937 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 8 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY
02:36:15,937 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator
02:36:15,938 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator
02:36:15,938 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
02:36:15,942 |-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:36:15,949 |-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:36:16,011 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - <level> element is deprecated. Near [level] on line 12
02:36:16,011 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within <logger> or <root> elements instead.
02:36:16,067 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE]
02:36:16,067 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
02:36:16,076 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
02:36:16,107 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN
02:36:16,108 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT]
02:36:16,108 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration.
02:36:16,109 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point
02:36:16,109 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 171 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY

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

================================================================================
---- Global Information --------------------------------------------------------
> request count                                    10497113 (OK=8212719 KO=2284394)
> min response time                                      3 (OK=33     KO=3     )
> max response time                                 105655 (OK=72753  KO=105655)
> mean response time                                  5070 (OK=4840   KO=5897  )
> std deviation                                       9344 (OK=7317   KO=14419 )
> response time 50th percentile                       2703 (OK=3195   KO=89    )
> response time 75th percentile                       5510 (OK=5713   KO=4198  )
> response time 95th percentile                      19525 (OK=16342  KO=60000 )
> response time 99th percentile                      60000 (OK=41654  KO=60037 )
> mean requests/sec                                3352.64 (OK=2623.03 KO=729.61)
---- Response Time Distribution ------------------------------------------------
> t < 800 ms                                       2321852 ( 22.12%)
> 800 ms <= t < 1200 ms                             320404 (  3.05%)
> t >= 1200 ms                                     5570463 ( 53.07%)
> failed                                           2284394 ( 21.76%)
---- Errors --------------------------------------------------------------------
> RootUserinfo: No attribute named 'access_token' is defined     1226430 (32.28%)
> j.n.ConnectException: connect(..) failed: Cannot assign reques 811530 (21.36%)
ted address
> status.find.in([200, 209], 304), found 500                     345796 ( 9.10%)
> status.find.in([200, 209], 304), found 503                     264769 ( 6.97%)
> headerRegex((Location,(?<=code=)(.+?)(?=&))).find.exists, foun 177649 ( 4.68%)
d nothing
> Request timeout to openam-perf-haproxy.forgeblocks.com/34.120. 118456 ( 3.12%)
144.60:443 after 60000 ms
> status.find.is(200), but actually found 503                    102778 ( 2.70%)
> status.find.is(302), but actually found 503                     98604 ( 2.59%)
> status.find.is(200), but actually found 401                     85983 ( 2.26%)
> status.find.in([200, 209], 304), found 401                      67883 ( 1.79%)
> deleteOrganization: No attribute named 'organization_id' is de  66892 ( 1.76%)
fined 
> queryOrganization: No attribute named 'organization_id' is def  66892 ( 1.76%)
ined 
> headerRegex((location,#id_token=(.*))).find.exists, found noth  66738 ( 1.76%)
ing
> createOrganization: No attribute named 'access_token' is defin  60984 ( 1.60%)
ed 
> status.find.in([200, 209], 304), found 502                      52491 ( 1.38%)
> status.find.is(200), but actually found 502                     45834 ( 1.21%)
> userinfo: No attribute named 'access_token' is defined          33173 ( 0.87%)
> jsonPath($.tokenId).find.exists, found nothing                  25197 ( 0.66%)
> registrationCallback: No attribute named 'callbacks' is define  16624 ( 0.44%)
d 
> tokenUserinfo: No attribute named 'access_token' is defined     12627 ( 0.33%)
>  UI AM revoke1: No attribute named 'access_token' is defined    11034 ( 0.29%)
>  UI AM endsession: No attribute named 'id_token_2' is defined    9331 ( 0.25%)
> status.find.in([200, 209], 304), found 404                       8971 ( 0.24%)
>  UI AM revoke2: No attribute named 'access_token_2' is defined   8911 ( 0.23%)
 
> status.find.is(302), but actually found 502                      7565 ( 0.20%)
> status.find.in([200, 209], 304), found 400                       2364 ( 0.06%)
> PatchManagedUser: No attribute named 'access_token' is defined   1257 ( 0.03%)
 
> ReadManagedUser: No attribute named 'access_token' is defined    1257 ( 0.03%)
> status.find.in(200), but actually found 500                       427 ( 0.01%)
> status.find.in(200,304), but actually found 500                   367 ( 0.01%)
> status.find.in(200), but actually found 502                       222 ( 0.01%)
> status.find.in(200,304), but actually found 503                   192 ( 0.01%)
> status.find.in([200, 209], 304), found 405                        141 ( 0.00%)
> status.find.in(200), but actually found 503                       127 ( 0.00%)
> status.find.in(200,304), but actually found 502                   112 ( 0.00%)
> status.find.in(200,304), but actually found 404                    70 ( 0.00%)
> status.find.in(200), but actually found 501                        56 ( 0.00%)
> status.find.in(200), but actually found 404                        30 ( 0.00%)
> j.i.IOException: Premature close                                   22 ( 0.00%)
> i.n.c.ConnectTimeoutException: connection timed out after 1000     10 ( 0.00%)
0 ms: openam-perf-haproxy.forgeblocks.com/34.120.144.60:443
> status.find.in(200,304), but actually found 401                     6 ( 0.00%)
> status.find.in(200), but actually found 401                         2 ( 0.00%)
> status.find.is(200), but actually found 403                         1 ( 0.00%)
> i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte      1 ( 0.00%)
r 10000ms
================================================================================

Reports generated, please open the following file: file:///results/gatling/benchmark-500/loadtest-20250919002032997/index.html
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  01:54 min
[INFO] Finished at: 2025-09-19T02:38:09+01:00
[INFO] ------------------------------------------------------------------------
2025-09-19 02:38:09,510 INFO    Gatling simulation run ended successfully
2025-09-19 02:38:09,511 INFO    Creating archive benchmark-500.tar.gz with report files
2025-09-19 02:38:10,893 INFO    Archive /results/gatling/benchmark-500/benchmark-500.tar.gz successfully created