--Task-- name: Reporting-query-6 enabled: True class_name: GatlingResultTask source_name: overseer-0 source_namespace: >default< target_name: overseer-0 target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates below loop: False interval: None dependencies: [] wait_for: ['Logs-parse-query-10', 'Logs-parse-query-9', 'Logs-parse-query-8', 'Logs-parse-query-7', 'Logs-parse-query-6', 'Logs-parse-query-5', 'Logs-parse-query-4', 'Reporting-query-10', 'Reporting-query-9', 'Reporting-query-8', 'Reporting-query-7'] preceding_task: None options: {'task_name': 'query-6'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 19:59:11 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 19:59:11 - INFO: timeout was set to GatlingResultTask default (half of global_duration or 3600 seconds (whatever is higher)), because it was unset Pod stdout file : /results/orders/Reporting-query-6/stdout.txt Pod stderr file : /results/orders/Reporting-query-6/stderr.txt Pod log : /results/orders/Reporting-query-6 ________________________________________________________________________________ [2025-03-28 19:59:11] Reporting-query-6 step1 : Write order for overseer to generate the report ________________________________________________________________________________ Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/order.json { "name": "Reporting-query-6", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/query-6\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"query-6.tar.gz\"", "out-dir": "/results/orders/Reporting-query-6" } ________________________________________________________________________________ [2025-03-28 19:59:11] Reporting-query-6 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 19:59:11,719 INFO 2025-03-28 19:59:11,719 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 19:59:11,719 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 19:59:11,934 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:11,934 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:11,934 DEBUG --- stdout --- 2025-03-28 19:59:11,934 DEBUG WAITING 2025-03-28 19:59:11,934 DEBUG --- stderr --- 2025-03-28 19:59:11,934 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2025-03-28 19:59:11] Reporting-query-6 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 19:59:11,935 INFO 2025-03-28 19:59:11,935 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2025-03-28 19:59:11,935 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:12,211 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:12,211 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:12,211 DEBUG --- stdout --- 2025-03-28 19:59:12,211 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:12,211 DEBUG --- stderr --- 2025-03-28 19:59:12,211 DEBUG 2025-03-28 19:59:12,212 INFO 2025-03-28 19:59:12,212 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 19:59:12,212 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:13,590 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:13,590 DEBUG --- stdout --- 2025-03-28 19:59:13,590 DEBUG 2025-03-28 19:59:13,590 DEBUG --- stderr --- 2025-03-28 19:59:13,590 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 19:59:13] Reporting-query-6 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 19:59:18,591 INFO 2025-03-28 19:59:18,591 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 19:59:18,591 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 19:59:18,810 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 19:59:24,025 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2025-03-28 19:59:24,025 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:24,025 DEBUG --- stdout --- 2025-03-28 19:59:24,025 DEBUG WAITING 2025-03-28 19:59:24,025 DEBUG --- stderr --- 2025-03-28 19:59:24,025 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2025-03-28 19:59:24] Reporting-query-6 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 19:59:24,025 INFO 2025-03-28 19:59:24,025 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2025-03-28 19:59:24,026 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:24,303 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:24,303 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:24,303 DEBUG --- stdout --- 2025-03-28 19:59:24,304 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:24,304 DEBUG --- stderr --- 2025-03-28 19:59:24,304 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:24,304 INFO 2025-03-28 19:59:24,304 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/gatling/query-6/query-6.tar.gz" ]; then echo "/results/gatling/query-6/query-6.tar.gz file found"; exit 0; else echo "/results/gatling/query-6/query-6.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/query-6/query-6.tar.gz file found" 2025-03-28 19:59:24,305 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:25,108 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:25,108 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:25,108 DEBUG --- stdout --- 2025-03-28 19:59:25,108 DEBUG /results/gatling/query-6/query-6.tar.gz file found 2025-03-28 19:59:25,108 DEBUG --- stderr --- 2025-03-28 19:59:25,108 DEBUG 2025-03-28 19:59:25,108 INFO 2025-03-28 19:59:25,109 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/gatling/query-6/query-6.tar.gz 2025-03-28 19:59:25,951 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:25,951 DEBUG --- stdout --- 2025-03-28 19:59:25,952 DEBUG 248 /results/gatling/query-6/query-6.tar.gz 2025-03-28 19:59:25,952 DEBUG --- stderr --- 2025-03-28 19:59:25,952 DEBUG 2025-03-28 19:59:25,952 INFO - Download file (size 248k) source : /results/gatling/query-6/query-6.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-6.tar.gz 2025-03-28 19:59:25,952 INFO 2025-03-28 19:59:25,952 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/query-6/query-6.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-6.tar.gz 2025-03-28 19:59:25,952 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:26,953 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:26,953 DEBUG --- stdout --- 2025-03-28 19:59:26,953 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:26,953 DEBUG --- stderr --- 2025-03-28 19:59:26,953 DEBUG ________________________________________________________________________________ [2025-03-28 19:59:26] Reporting-query-6 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 19:59:26,953 INFO 2025-03-28 19:59:26,953 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-6.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_query-6/gatling 2025-03-28 19:59:26,966 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:26,966 DEBUG --- stdout --- 2025-03-28 19:59:26,966 DEBUG 2025-03-28 19:59:26,966 DEBUG --- stderr --- 2025-03-28 19:59:26,966 DEBUG 2025-03-28 19:59:26,966 INFO ________________________________________________________________________________ [2025-03-28 19:59:26] Reporting-query-6 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 19:59:26,966 INFO 2025-03-28 19:59:26,967 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2025-03-28 19:59:26,967 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:27,246 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:27,246 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:27,246 DEBUG --- stdout --- 2025-03-28 19:59:27,246 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:27,246 DEBUG --- stderr --- 2025-03-28 19:59:27,246 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:27,247 INFO 2025-03-28 19:59:27,247 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/Reporting-query-6/rc.txt" ]; then echo "/results/orders/Reporting-query-6/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-query-6/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-6/rc.txt file found" 2025-03-28 19:59:27,247 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:28,051 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:28,051 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:28,051 DEBUG --- stdout --- 2025-03-28 19:59:28,051 DEBUG /results/orders/Reporting-query-6/rc.txt file found 2025-03-28 19:59:28,051 DEBUG --- stderr --- 2025-03-28 19:59:28,051 DEBUG 2025-03-28 19:59:28,051 INFO 2025-03-28 19:59:28,051 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/Reporting-query-6/rc.txt 2025-03-28 19:59:28,844 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:28,844 DEBUG --- stdout --- 2025-03-28 19:59:28,844 DEBUG 4 /results/orders/Reporting-query-6/rc.txt 2025-03-28 19:59:28,844 DEBUG --- stderr --- 2025-03-28 19:59:28,844 DEBUG 2025-03-28 19:59:28,844 INFO - Download file (size 4k) source : /results/orders/Reporting-query-6/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/rc.txt 2025-03-28 19:59:28,845 INFO 2025-03-28 19:59:28,845 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-query-6/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/rc.txt 2025-03-28 19:59:28,845 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:29,566 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:29,567 DEBUG --- stdout --- 2025-03-28 19:59:29,567 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:29,567 DEBUG --- stderr --- 2025-03-28 19:59:29,567 DEBUG 2025-03-28 19:59:29,567 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/rc.txt is small (1 lines), printing all lines: 2025-03-28 19:59:29,567 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 19:59:29] Reporting-query-6 step8 : Check rc log ________________________________________________________________________________ CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) PASS: no error found in rc log ________________________________________________________________________________ [2025-03-28 19:59:29] Reporting-query-6 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 19:59:29,858 INFO 2025-03-28 19:59:29,858 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2025-03-28 19:59:29,858 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:30,136 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:30,136 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:30,136 DEBUG --- stdout --- 2025-03-28 19:59:30,136 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:30,136 DEBUG --- stderr --- 2025-03-28 19:59:30,136 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:30,137 INFO 2025-03-28 19:59:30,137 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/Reporting-query-6/stdout.txt" ]; then echo "/results/orders/Reporting-query-6/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-query-6/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-6/stdout.txt file found" 2025-03-28 19:59:30,137 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:30,942 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:30,942 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:30,942 DEBUG --- stdout --- 2025-03-28 19:59:30,942 DEBUG /results/orders/Reporting-query-6/stdout.txt file found 2025-03-28 19:59:30,942 DEBUG --- stderr --- 2025-03-28 19:59:30,942 DEBUG 2025-03-28 19:59:30,942 INFO 2025-03-28 19:59:30,943 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/Reporting-query-6/stdout.txt 2025-03-28 19:59:31,737 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:31,737 DEBUG --- stdout --- 2025-03-28 19:59:31,737 DEBUG 8 /results/orders/Reporting-query-6/stdout.txt 2025-03-28 19:59:31,737 DEBUG --- stderr --- 2025-03-28 19:59:31,737 DEBUG 2025-03-28 19:59:31,737 INFO - Download file (size 8k) source : /results/orders/Reporting-query-6/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/stdout.txt 2025-03-28 19:59:31,738 INFO 2025-03-28 19:59:31,738 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-query-6/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/stdout.txt 2025-03-28 19:59:31,738 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:32,463 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:32,463 DEBUG --- stdout --- 2025-03-28 19:59:32,463 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:32,463 DEBUG --- stderr --- 2025-03-28 19:59:32,463 DEBUG 2025-03-28 19:59:32,463 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/stdout.txt is small (90 lines), printing all lines: 2025-03-28 19:59:32,463 INFO ----- output ----- 2025-03-28 19:59:14,239 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpvze2gtiu 2025-03-28 19:59:14,241 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/query-6/loadtest-20250328192518662" -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx8G" [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/tmpvze2gtiu/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/tmpvze2gtiu/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 --- 19:59:16,079 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 19:59:16,080 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 19:59:16,080 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 19:59:16,081 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 19:59:16,086 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 19:59:16,086 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 19:59:16,094 |-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 19:59:16,094 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 19:59:16,094 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 19:59:16,095 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 19:59:16,097 |-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] 19:59:16,102 |-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 19:59:16,145 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 19:59:16,145 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 19:59:16,185 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 19:59:16,185 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 19:59:16,191 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 19:59:16,212 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 19:59:16,212 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 19:59:16,213 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 19:59:16,213 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 19:59:16,213 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 119 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY Parsing log file(s)... Parsing log file(s) done in 1s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 29502 (OK=29499 KO=3 ) > min response time 40 (OK=40 KO=60000 ) > max response time 60001 (OK=354 KO=60001 ) > mean response time 54 (OK=48 KO=60000 ) > std deviation 605 (OK=5 KO=0 ) > response time 50th percentile 47 (OK=47 KO=60000 ) > response time 75th percentile 49 (OK=49 KO=60001 ) > response time 95th percentile 52 (OK=52 KO=60001 ) > response time 99th percentile 61 (OK=61 KO=60001 ) > mean requests/sec 49.17 (OK=49.16 KO=0.01 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 29499 ( 99.99%) > 800 ms <= t < 1200 ms 0 ( 0%) > t >= 1200 ms 0 ( 0%) > failed 3 ( 0.01%) ---- Errors -------------------------------------------------------------------- > Request timeout to openam-perf-200m.forgeblocks.com/34.107.203 3 (100.0%) .37:443 after 60000 ms ================================================================================ Reports generated, please open the following file: file:///results/gatling/query-6/loadtest-20250328192518662/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 3.475 s [INFO] Finished at: 2025-03-28T19:59:18Z [INFO] ------------------------------------------------------------------------ 2025-03-28 19:59:18,599 INFO Gatling simulation run ended successfully 2025-03-28 19:59:18,599 INFO Creating archive query-6.tar.gz with report files 2025-03-28 19:59:18,666 INFO Archive /results/gatling/query-6/query-6.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 19:59:32] Reporting-query-6 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 19:59:32] Reporting-query-6 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 19:59:32,464 INFO 2025-03-28 19:59:32,464 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2025-03-28 19:59:32,464 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:32,742 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:32,742 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:32,742 DEBUG --- stdout --- 2025-03-28 19:59:32,742 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:32,742 DEBUG --- stderr --- 2025-03-28 19:59:32,742 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:32,743 INFO 2025-03-28 19:59:32,743 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/Reporting-query-6/stderr.txt" ]; then echo "/results/orders/Reporting-query-6/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-query-6/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-6/stderr.txt file found" 2025-03-28 19:59:32,743 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:33,541 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:33,541 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:33,541 DEBUG --- stdout --- 2025-03-28 19:59:33,541 DEBUG /results/orders/Reporting-query-6/stderr.txt file found 2025-03-28 19:59:33,541 DEBUG --- stderr --- 2025-03-28 19:59:33,541 DEBUG 2025-03-28 19:59:33,541 INFO 2025-03-28 19:59:33,542 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/Reporting-query-6/stderr.txt 2025-03-28 19:59:34,345 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:34,345 DEBUG --- stdout --- 2025-03-28 19:59:34,345 DEBUG 0 /results/orders/Reporting-query-6/stderr.txt 2025-03-28 19:59:34,345 DEBUG --- stderr --- 2025-03-28 19:59:34,345 DEBUG 2025-03-28 19:59:34,345 INFO - Download file (size 0k) source : /results/orders/Reporting-query-6/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/stderr.txt 2025-03-28 19:59:34,346 INFO 2025-03-28 19:59:34,346 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-query-6/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/stderr.txt 2025-03-28 19:59:34,346 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:35,074 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:35,074 DEBUG --- stdout --- 2025-03-28 19:59:35,075 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:35,075 DEBUG --- stderr --- 2025-03-28 19:59:35,075 DEBUG 2025-03-28 19:59:35,075 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/stderr.txt is small (0 lines), printing all lines: 2025-03-28 19:59:35,075 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 19:59:35] Reporting-query-6 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 19:59:35] Reporting-query-6 step13 : Analyze graphs for spikes ________________________________________________________________________________ Analyze Gatling graphs for spikes checking parameters open overall stats JSON file checking simulation request restLoginInitiate checking simulation request restLoginUsernamePasswordCallback checking simulation request Skip 2FA checking simulation request User Authorize PKCE stage checking simulation request User AccessToken PKCE stage checking simulation request queryPartial [warn] responseTimePercentiles was not found in /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_query-6/gatling/req_querypartial-650532761.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 19:59:35] Reporting-query-6 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 29502, "ok": 29499, "ko": 3 }, "minResponseTime": { "total": 40, "ok": 40, "ko": 60000 }, "maxResponseTime": { "total": 60001, "ok": 354, "ko": 60001 }, "meanResponseTime": { "total": 54, "ok": 48, "ko": 60000 }, "standardDeviation": { "total": 605, "ok": 5, "ko": 0 }, "percentiles1": { "total": 47, "ok": 47, "ko": 60000 }, "percentiles2": { "total": 49, "ok": 49, "ko": 60001 }, "percentiles3": { "total": 52, "ok": 52, "ko": 60001 }, "percentiles4": { "total": 61, "ok": 61, "ko": 60001 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 29499, "percentage": 99.9898311978849 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 3, "percentage": 0.01016880211511084 }, "meanNumberOfRequestsPerSecond": { "total": 49.17, "ok": 49.165, "ko": 0.005 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 64, "ok": 64, "ko": 0 }, "maxResponseTime": { "total": 64, "ok": 64, "ko": 0 }, "meanResponseTime": { "total": 64, "ok": 64, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 64, "ok": 64, "ko": 0 }, "percentiles2": { "total": 64, "ok": 64, "ko": 0 }, "percentiles3": { "total": 64, "ok": 64, "ko": 0 }, "percentiles4": { "total": 64, "ok": 64, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0016666666666666668, "ok": 0.0016666666666666668, "ko": 0 }, "spikes": [] }, { "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 110, "ok": 110, "ko": 0 }, "maxResponseTime": { "total": 110, "ok": 110, "ko": 0 }, "meanResponseTime": { "total": 110, "ok": 110, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 110, "ok": 110, "ko": 0 }, "percentiles2": { "total": 110, "ok": 110, "ko": 0 }, "percentiles3": { "total": 110, "ok": 110, "ko": 0 }, "percentiles4": { "total": 110, "ok": 110, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0016666666666666668, "ok": 0.0016666666666666668, "ko": 0 }, "spikes": [] }, { "name": "Skip 2FA", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 89, "ok": 89, "ko": 0 }, "maxResponseTime": { "total": 89, "ok": 89, "ko": 0 }, "meanResponseTime": { "total": 89, "ok": 89, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 89, "ok": 89, "ko": 0 }, "percentiles2": { "total": 89, "ok": 89, "ko": 0 }, "percentiles3": { "total": 89, "ok": 89, "ko": 0 }, "percentiles4": { "total": 89, "ok": 89, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0016666666666666668, "ok": 0.0016666666666666668, "ko": 0 }, "spikes": [] }, { "name": "User Authorize PKCE stage", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 62, "ok": 62, "ko": 0 }, "maxResponseTime": { "total": 62, "ok": 62, "ko": 0 }, "meanResponseTime": { "total": 62, "ok": 62, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 62, "ok": 62, "ko": 0 }, "percentiles2": { "total": 62, "ok": 62, "ko": 0 }, "percentiles3": { "total": 62, "ok": 62, "ko": 0 }, "percentiles4": { "total": 62, "ok": 62, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0016666666666666668, "ok": 0.0016666666666666668, "ko": 0 }, "spikes": [] }, { "name": "User AccessToken PKCE stage", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 64, "ok": 64, "ko": 0 }, "maxResponseTime": { "total": 64, "ok": 64, "ko": 0 }, "meanResponseTime": { "total": 64, "ok": 64, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 64, "ok": 64, "ko": 0 }, "percentiles2": { "total": 64, "ok": 64, "ko": 0 }, "percentiles3": { "total": 64, "ok": 64, "ko": 0 }, "percentiles4": { "total": 64, "ok": 64, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0016666666666666668, "ok": 0.0016666666666666668, "ko": 0 }, "spikes": [] }, { "name": "queryPartial", "numberOfRequests": { "total": 29497, "ok": 29494, "ko": 3 }, "minResponseTime": { "total": 40, "ok": 40, "ko": 60000 }, "maxResponseTime": { "total": 60001, "ok": 354, "ko": 60001 }, "meanResponseTime": { "total": 54, "ok": 48, "ko": 60000 }, "standardDeviation": { "total": 605, "ok": 5, "ko": 0 }, "percentiles1": { "total": 47, "ok": 47, "ko": 60000 }, "percentiles2": { "total": 49, "ok": 49, "ko": 60001 }, "percentiles3": { "total": 52, "ok": 52, "ko": 60001 }, "percentiles4": { "total": 61, "ok": 60, "ko": 60001 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 29494, "percentage": 99.98982947418381 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 3, "percentage": 0.010170525816184696 }, "meanNumberOfRequestsPerSecond": { "total": 49.16166666666667, "ok": 49.156666666666666, "ko": 0.005 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 49, "avg_response_time": 54, "max_response_time": 354, "min_response_time": 40, "num_requests": 29502, "num_requests_pass": 29499, "num_requests_percent_pass": 99.99 }, "options": "; testMode:open; testCase:idm.ManagedUsersQueryPartial; duration:600; throughput:50", "stats": { "details": [ { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 64, "total": 64 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 64, "total": 64 }, "minResponseTime": { "ko": 0, "ok": 64, "total": 64 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 64, "total": 64 }, "percentiles2": { "ko": 0, "ok": 64, "total": 64 }, "percentiles3": { "ko": 0, "ok": 64, "total": 64 }, "percentiles4": { "ko": 0, "ok": 64, "total": 64 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 110, "total": 110 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 110, "total": 110 }, "minResponseTime": { "ko": 0, "ok": 110, "total": 110 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 110, "total": 110 }, "percentiles2": { "ko": 0, "ok": 110, "total": 110 }, "percentiles3": { "ko": 0, "ok": 110, "total": 110 }, "percentiles4": { "ko": 0, "ok": 110, "total": 110 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 89, "total": 89 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 89, "total": 89 }, "minResponseTime": { "ko": 0, "ok": 89, "total": 89 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 89, "total": 89 }, "percentiles2": { "ko": 0, "ok": 89, "total": 89 }, "percentiles3": { "ko": 0, "ok": 89, "total": 89 }, "percentiles4": { "ko": 0, "ok": 89, "total": 89 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 62, "total": 62 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 62, "total": 62 }, "minResponseTime": { "ko": 0, "ok": 62, "total": 62 }, "name": "User Authorize PKCE stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 62, "total": 62 }, "percentiles2": { "ko": 0, "ok": 62, "total": 62 }, "percentiles3": { "ko": 0, "ok": 62, "total": 62 }, "percentiles4": { "ko": 0, "ok": 62, "total": 62 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 64, "total": 64 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 64, "total": 64 }, "minResponseTime": { "ko": 0, "ok": 64, "total": 64 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 64, "total": 64 }, "percentiles2": { "ko": 0, "ok": 64, "total": 64 }, "percentiles3": { "ko": 0, "ok": 64, "total": 64 }, "percentiles4": { "ko": 0, "ok": 64, "total": 64 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 29494, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.98982947418381 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 3, "htmlName": "failed", "name": "failed", "percentage": 0.010170525816184696 }, "maxResponseTime": { "ko": 60001, "ok": 354, "total": 60001 }, "meanNumberOfRequestsPerSecond": { "ko": 0.005, "ok": 49.156666666666666, "total": 49.16166666666667 }, "meanResponseTime": { "ko": 60000, "ok": 48, "total": 54 }, "minResponseTime": { "ko": 60000, "ok": 40, "total": 40 }, "name": "queryPartial", "numberOfRequests": { "ko": 3, "ok": 29494, "total": 29497 }, "percentiles1": { "ko": 60000, "ok": 47, "total": 47 }, "percentiles2": { "ko": 60001, "ok": 49, "total": 49 }, "percentiles3": { "ko": 60001, "ok": 52, "total": 52 }, "percentiles4": { "ko": 60001, "ok": 60, "total": 61 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 5, "total": 605 } } ], "global": { "group1": { "count": 29499, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.9898311978849 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 3, "htmlName": "failed", "name": "failed", "percentage": 0.01016880211511084 }, "maxResponseTime": { "ko": 60001, "ok": 354, "total": 60001 }, "meanNumberOfRequestsPerSecond": { "ko": 0.005, "ok": 49.165, "total": 49.17 }, "meanResponseTime": { "ko": 60000, "ok": 48, "total": 54 }, "minResponseTime": { "ko": 60000, "ok": 40, "total": 40 }, "name": "All Requests", "numberOfRequests": { "ko": 3, "ok": 29499, "total": 29502 }, "percentiles1": { "ko": 60000, "ok": 47, "total": 47 }, "percentiles2": { "ko": 60001, "ok": 49, "total": 49 }, "percentiles3": { "ko": 60001, "ok": 52, "total": 52 }, "percentiles4": { "ko": 60001, "ok": 61, "total": 61 }, "standardDeviation": { "ko": 0, "ok": 5, "total": 605 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 19:59:35] Reporting-query-6 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 19:59:35] Reporting-query-6 step16 : Remove gatling task's stdout file ________________________________________________________________________________ Cleanup of Gatling stdout file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-query-6/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 19:59:35] Reporting-query-6 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped