2024-11-07 02:22:58,452 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp4doy77tr 2024-11-07 02:22:58,453 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/benchmark-130/loadtest-20241107011453546" -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/tmp4doy77tr/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/tmp4doy77tr/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:23:00,077 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 02:23:00,078 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 02:23:00,078 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 02:23:00,079 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 02:23:00,084 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 02:23:00,084 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 02:23:00,091 |-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:23:00,091 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 02:23:00,092 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 02:23:00,092 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 02:23:00,095 |-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:23:00,099 |-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:23:00,138 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 02:23:00,138 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 02:23:00,173 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 02:23:00,173 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 02:23:00,179 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 02:23:00,199 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 02:23:00,199 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 02:23:00,199 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 02:23:00,200 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 02:23:00,200 |-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 24s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 4345691 (OK=3296307 KO=1049384) > min response time 35 (OK=35 KO=35 ) > max response time 79613 (OK=79613 KO=60775 ) > mean response time 9016 (OK=9473 KO=7579 ) > std deviation 12006 (OK=11210 KO=14124 ) > response time 50th percentile 4051 (OK=5429 KO=41 ) > response time 75th percentile 14116 (OK=15094 KO=10307 ) > response time 95th percentile 33444 (OK=32497 KO=41769 ) > response time 99th percentile 58244 (OK=47434 KO=60000 ) > mean requests/sec 1207.14 (OK=915.64 KO=291.5 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 1065593 ( 24.52%) > 800 ms <= t < 1200 ms 68113 ( 1.57%) > t >= 1200 ms 2162601 ( 49.76%) > failed 1049384 ( 24.15%) ---- Errors -------------------------------------------------------------------- > status.find.in([200, 209], 304), found 503 519508 (36.90%) > RootUserinfo: No attribute named 'access_token' is defined 143711 (10.21%) > status.find.is(302), but actually found 503 131266 ( 9.32%) > status.find.in([200, 209], 304), found 404 127487 ( 9.06%) > CreatePolicy: No attribute named 'oauth2_uuId' is defined 78432 ( 5.57%) > status.find.in([200, 209], 304), found 500 66157 ( 4.70%) > userinfo: No attribute named 'access_token' is defined 53057 ( 3.77%) > status.find.is(200), but actually found 503 40194 ( 2.86%) > Request timeout to openam-perf-benchmark.forgeblocks.com/34.8. 39915 ( 2.84%) 9.233:443 after 60000 ms > headerRegex((Location,(?<=code=)(.+?)(?=&))).find.exists, foun 38291 ( 2.72%) d nothing > status.find.in([200, 209], 304), found 502 20936 ( 1.49%) > status.find.is(302), but actually found 502 19470 ( 1.38%) > tokenUserinfo: No attribute named 'access_token' is defined 19082 ( 1.36%) > deleteOrganization: No attribute named 'organization_id' is de 18030 ( 1.28%) fined > queryOrganization: No attribute named 'organization_id' is def 18030 ( 1.28%) ined > status.find.is(200), but actually found 502 15417 ( 1.10%) > createOrganization: No attribute named 'access_token' is defin 14514 ( 1.03%) ed > status.find.is(200), but actually found 401 13205 ( 0.94%) > status.find.in([200, 209], 304), found 401 7776 ( 0.55%) > UI AM revoke2: No attribute named 'access_token_2' is defined 4733 ( 0.34%) > UI AM revoke1: No attribute named 'access_token' is defined 4707 ( 0.33%) > UI AM endsession: No attribute named 'id_token_2' is defined 4165 ( 0.30%) > status.find.in([200, 209], 304), found 403 3864 ( 0.27%) > headerRegex((location,#id_token=(.*))).find.exists, found noth 3397 ( 0.24%) ing > status.find.in([200, 209], 304), found 400 1079 ( 0.08%) > jsonPath($.result[1].uuid).find.exists, found nothing 1071 ( 0.08%) > status.find.in([200, 209], 304), found 409 337 ( 0.02%) > status.find.is(302), but actually found 400 13 ( 0.00%) > j.i.IOException: Premature close 1 ( 0.00%) ================================================================================ Reports generated, please open the following file: file:///results/gatling/benchmark-130/loadtest-20241107011453546/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 27.080 s [INFO] Finished at: 2024-11-07T02:23:26Z [INFO] ------------------------------------------------------------------------ 2024-11-07 02:23:26,315 INFO Gatling simulation run ended successfully 2024-11-07 02:23:26,316 INFO Creating archive benchmark-130.tar.gz with report files 2024-11-07 02:23:27,027 INFO Archive /results/gatling/benchmark-130/benchmark-130.tar.gz successfully created