2025-09-17 12:15:12,661 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpn59wnp5j 2025-09-17 12:15:12,663 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/benchmark-500/loadtest-20250917100318743" -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/tmpn59wnp5j/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/tmpn59wnp5j/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 --- 12:15:15,243 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 12:15:15,245 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 12:15:15,245 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 12:15:15,247 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 12:15:15,253 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 12:15:15,254 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 12:15:15,263 |-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 12:15:15,263 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 12:15:15,264 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 12:15:15,265 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 12:15:15,268 |-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] 12:15:15,275 |-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 12:15:15,335 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 12:15:15,335 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 12:15:15,391 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 12:15:15,392 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 12:15:15,400 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 12:15:15,431 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 12:15:15,431 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 12:15:15,432 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 12:15:15,432 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 12:15:15,432 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 168 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY Parsing log file(s)... Parsing log file(s) done in 115s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 11707283 (OK=9617447 KO=2089836) > min response time 3 (OK=33 KO=3 ) > max response time 64767 (OK=62026 KO=64767 ) > mean response time 4484 (OK=4476 KO=4518 ) > std deviation 7100 (OK=6133 KO=10453 ) > response time 50th percentile 2960 (OK=3222 KO=1078 ) > response time 75th percentile 5592 (OK=5731 KO=4787 ) > response time 95th percentile 13632 (OK=13386 KO=15594 ) > response time 99th percentile 41442 (OK=33603 KO=60000 ) > mean requests/sec 3906.33 (OK=3209.02 KO=697.31) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 2665103 ( 22.76%) > 800 ms <= t < 1200 ms 396424 ( 3.39%) > t >= 1200 ms 6555920 ( 56%) > failed 2089836 ( 17.85%) ---- Errors -------------------------------------------------------------------- > RootUserinfo: No attribute named 'access_token' is defined 1590903 (39.62%) > j.n.ConnectException: connect(..) failed: Cannot assign reques 729772 (18.18%) ted address > status.find.in([200, 209], 304), found 500 482549 (12.02%) > headerRegex((Location,(?<=code=)(.+?)(?=&))).find.exists, foun 260342 ( 6.48%) d nothing > status.find.is(200), but actually found 401 116791 ( 2.91%) > status.find.in([200, 209], 304), found 503 105735 ( 2.63%) > status.find.in([200, 209], 304), found 401 92593 ( 2.31%) > headerRegex((location,#id_token=(.*))).find.exists, found noth 90921 ( 2.26%) ing > deleteOrganization: No attribute named 'organization_id' is de 83403 ( 2.08%) fined > queryOrganization: No attribute named 'organization_id' is def 83403 ( 2.08%) ined > createOrganization: No attribute named 'access_token' is defin 79155 ( 1.97%) ed > status.find.in([200, 209], 304), found 502 46966 ( 1.17%) > Request timeout to openam-perf-haproxy.forgeblocks.com/34.120. 46897 ( 1.17%) 144.60:443 after 60000 ms > status.find.is(302), but actually found 503 31250 ( 0.78%) > jsonPath($.tokenId).find.exists, found nothing 28194 ( 0.70%) > userinfo: No attribute named 'access_token' is defined 25917 ( 0.65%) > status.find.is(200), but actually found 503 23288 ( 0.58%) > status.find.in([200, 209], 304), found 404 17238 ( 0.43%) > UI AM revoke1: No attribute named 'access_token' is defined 15416 ( 0.38%) > UI AM endsession: No attribute named 'id_token_2' is defined 13426 ( 0.33%) > UI AM revoke2: No attribute named 'access_token_2' is defined 13041 ( 0.32%) > registrationCallback: No attribute named 'callbacks' is define 11236 ( 0.28%) d > status.find.is(200), but actually found 502 7423 ( 0.18%) > tokenUserinfo: No attribute named 'access_token' is defined 6942 ( 0.17%) > status.find.is(302), but actually found 502 6259 ( 0.16%) > status.find.in([200, 209], 304), found 400 1900 ( 0.05%) > PatchManagedUser: No attribute named 'access_token' is defined 1151 ( 0.03%) > ReadManagedUser: No attribute named 'access_token' is defined 1151 ( 0.03%) > status.find.in(200), but actually found 502 295 ( 0.01%) > status.find.in(200), but actually found 500 236 ( 0.01%) > status.find.in([200, 209], 304), found 405 225 ( 0.01%) > status.find.in(200,304), but actually found 503 205 ( 0.01%) > status.find.in(200,304), but actually found 500 197 ( 0.00%) > status.find.in(200,304), but actually found 502 140 ( 0.00%) > status.find.in(200), but actually found 503 129 ( 0.00%) > status.find.in(200,304), but actually found 404 109 ( 0.00%) > status.find.in(200), but actually found 501 63 ( 0.00%) > status.find.in(200), but actually found 404 58 ( 0.00%) > j.i.IOException: Premature close 45 ( 0.00%) > i.n.c.ConnectTimeoutException: connection timed out after 1000 13 ( 0.00%) 0 ms: openam-perf-haproxy.forgeblocks.com/34.120.144.60:443 > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 3 ( 0.00%) r 10000ms ================================================================================ Reports generated, please open the following file: file:///results/gatling/benchmark-500/loadtest-20250917100318743/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 01:59 min [INFO] Finished at: 2025-09-17T12:17:13+01:00 [INFO] ------------------------------------------------------------------------ 2025-09-17 12:17:13,824 INFO Gatling simulation run ended successfully 2025-09-17 12:17:13,824 INFO Creating archive benchmark-500.tar.gz with report files 2025-09-17 12:17:15,304 INFO Archive /results/gatling/benchmark-500/benchmark-500.tar.gz successfully created