--Task-- name: Reporting-query-5 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', 'Reporting-query-6'] preceding_task: None options: {'task_name': 'query-5'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 19:59:35 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 19:59:35 - 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-5/stdout.txt Pod stderr file : /results/orders/Reporting-query-5/stderr.txt Pod log : /results/orders/Reporting-query-5 ________________________________________________________________________________ [2025-03-28 19:59:35] Reporting-query-5 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/14_Reporting-query-5/order.json { "name": "Reporting-query-5", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/query-5\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"query-5.tar.gz\"", "out-dir": "/results/orders/Reporting-query-5" } ________________________________________________________________________________ [2025-03-28 19:59:35] Reporting-query-5 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 19:59:35,084 INFO 2025-03-28 19:59:35,084 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 19:59:35,084 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 19:59:35,299 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:35,299 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:35,299 DEBUG --- stdout --- 2025-03-28 19:59:35,299 DEBUG WAITING 2025-03-28 19:59:35,299 DEBUG --- stderr --- 2025-03-28 19:59:35,299 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:35] Reporting-query-5 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 19:59:35,300 INFO 2025-03-28 19:59:35,300 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:35,300 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:35,577 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:35,577 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:35,578 DEBUG --- stdout --- 2025-03-28 19:59:35,578 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:35,578 DEBUG --- stderr --- 2025-03-28 19:59:35,578 DEBUG 2025-03-28 19:59:35,578 INFO 2025-03-28 19:59:35,578 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/14_Reporting-query-5/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 19:59:35,578 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:36,948 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:36,948 DEBUG --- stdout --- 2025-03-28 19:59:36,948 DEBUG 2025-03-28 19:59:36,948 DEBUG --- stderr --- 2025-03-28 19:59:36,948 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 19:59:36] Reporting-query-5 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 19:59:41,948 INFO 2025-03-28 19:59:41,949 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 19:59:41,949 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 19:59:42,164 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 19:59:47,378 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2025-03-28 19:59:47,379 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:47,379 DEBUG --- stdout --- 2025-03-28 19:59:47,379 DEBUG WAITING 2025-03-28 19:59:47,379 DEBUG --- stderr --- 2025-03-28 19:59:47,379 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:47] Reporting-query-5 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 19:59:47,379 INFO 2025-03-28 19:59:47,380 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:47,380 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:47,659 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:47,659 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:47,659 DEBUG --- stdout --- 2025-03-28 19:59:47,659 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:47,659 DEBUG --- stderr --- 2025-03-28 19:59:47,660 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:47,660 INFO 2025-03-28 19:59:47,660 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-5/query-5.tar.gz" ]; then echo "/results/gatling/query-5/query-5.tar.gz file found"; exit 0; else echo "/results/gatling/query-5/query-5.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/query-5/query-5.tar.gz file found" 2025-03-28 19:59:47,660 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:48,462 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:48,462 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:48,462 DEBUG --- stdout --- 2025-03-28 19:59:48,462 DEBUG /results/gatling/query-5/query-5.tar.gz file found 2025-03-28 19:59:48,462 DEBUG --- stderr --- 2025-03-28 19:59:48,462 DEBUG 2025-03-28 19:59:48,462 INFO 2025-03-28 19:59:48,462 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-5/query-5.tar.gz 2025-03-28 19:59:49,272 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:49,272 DEBUG --- stdout --- 2025-03-28 19:59:49,272 DEBUG 252 /results/gatling/query-5/query-5.tar.gz 2025-03-28 19:59:49,272 DEBUG --- stderr --- 2025-03-28 19:59:49,272 DEBUG 2025-03-28 19:59:49,272 INFO - Download file (size 252k) source : /results/gatling/query-5/query-5.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-5.tar.gz 2025-03-28 19:59:49,273 INFO 2025-03-28 19:59:49,273 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/query-5/query-5.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-5.tar.gz 2025-03-28 19:59:49,273 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:50,273 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:50,274 DEBUG --- stdout --- 2025-03-28 19:59:50,274 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:50,274 DEBUG --- stderr --- 2025-03-28 19:59:50,274 DEBUG ________________________________________________________________________________ [2025-03-28 19:59:50] Reporting-query-5 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 19:59:50,274 INFO 2025-03-28 19:59:50,274 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-5.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/06_query-5/gatling 2025-03-28 19:59:50,287 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:50,287 DEBUG --- stdout --- 2025-03-28 19:59:50,287 DEBUG 2025-03-28 19:59:50,287 DEBUG --- stderr --- 2025-03-28 19:59:50,287 DEBUG 2025-03-28 19:59:50,287 INFO ________________________________________________________________________________ [2025-03-28 19:59:50] Reporting-query-5 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 19:59:50,288 INFO 2025-03-28 19:59:50,288 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:50,288 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:50,565 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:50,565 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:50,565 DEBUG --- stdout --- 2025-03-28 19:59:50,565 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:50,565 DEBUG --- stderr --- 2025-03-28 19:59:50,565 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:50,566 INFO 2025-03-28 19:59:50,566 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-5/rc.txt" ]; then echo "/results/orders/Reporting-query-5/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-query-5/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-5/rc.txt file found" 2025-03-28 19:59:50,566 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:51,383 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:51,383 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:51,383 DEBUG --- stdout --- 2025-03-28 19:59:51,383 DEBUG /results/orders/Reporting-query-5/rc.txt file found 2025-03-28 19:59:51,383 DEBUG --- stderr --- 2025-03-28 19:59:51,383 DEBUG 2025-03-28 19:59:51,383 INFO 2025-03-28 19:59:51,384 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-5/rc.txt 2025-03-28 19:59:52,193 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:52,193 DEBUG --- stdout --- 2025-03-28 19:59:52,193 DEBUG 4 /results/orders/Reporting-query-5/rc.txt 2025-03-28 19:59:52,193 DEBUG --- stderr --- 2025-03-28 19:59:52,193 DEBUG 2025-03-28 19:59:52,193 INFO - Download file (size 4k) source : /results/orders/Reporting-query-5/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-query-5/rc.txt 2025-03-28 19:59:52,194 INFO 2025-03-28 19:59:52,194 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-5/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-query-5/rc.txt 2025-03-28 19:59:52,194 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:52,937 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:52,938 DEBUG --- stdout --- 2025-03-28 19:59:52,938 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:52,938 DEBUG --- stderr --- 2025-03-28 19:59:52,938 DEBUG 2025-03-28 19:59:52,938 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-query-5/rc.txt is small (1 lines), printing all lines: 2025-03-28 19:59:52,938 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 19:59:52] Reporting-query-5 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:53] Reporting-query-5 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 19:59:53,175 INFO 2025-03-28 19:59:53,175 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:53,175 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:53,454 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:53,454 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:53,454 DEBUG --- stdout --- 2025-03-28 19:59:53,454 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:53,454 DEBUG --- stderr --- 2025-03-28 19:59:53,454 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:53,455 INFO 2025-03-28 19:59:53,455 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-5/stdout.txt" ]; then echo "/results/orders/Reporting-query-5/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-query-5/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-5/stdout.txt file found" 2025-03-28 19:59:53,455 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:54,260 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:54,260 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:54,260 DEBUG --- stdout --- 2025-03-28 19:59:54,260 DEBUG /results/orders/Reporting-query-5/stdout.txt file found 2025-03-28 19:59:54,260 DEBUG --- stderr --- 2025-03-28 19:59:54,260 DEBUG 2025-03-28 19:59:54,260 INFO 2025-03-28 19:59:54,260 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-5/stdout.txt 2025-03-28 19:59:55,062 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:55,062 DEBUG --- stdout --- 2025-03-28 19:59:55,062 DEBUG 8 /results/orders/Reporting-query-5/stdout.txt 2025-03-28 19:59:55,062 DEBUG --- stderr --- 2025-03-28 19:59:55,062 DEBUG 2025-03-28 19:59:55,062 INFO - Download file (size 8k) source : /results/orders/Reporting-query-5/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-query-5/stdout.txt 2025-03-28 19:59:55,063 INFO 2025-03-28 19:59:55,063 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-5/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-query-5/stdout.txt 2025-03-28 19:59:55,063 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:55,860 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:55,860 DEBUG --- stdout --- 2025-03-28 19:59:55,860 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:55,860 DEBUG --- stderr --- 2025-03-28 19:59:55,860 DEBUG 2025-03-28 19:59:55,860 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-query-5/stdout.txt is small (90 lines), printing all lines: 2025-03-28 19:59:55,860 INFO ----- output ----- 2025-03-28 19:59:37,748 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmps_mm08zf 2025-03-28 19:59:37,750 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/query-5/loadtest-20250328193550222" -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/tmps_mm08zf/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/tmps_mm08zf/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:39,593 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 19:59:39,594 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 19:59:39,594 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 19:59:39,595 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 19:59:39,601 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 19:59:39,601 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 19:59:39,608 |-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:39,608 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 19:59:39,609 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 19:59:39,609 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 19:59:39,612 |-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:39,616 |-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:39,660 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 19:59:39,660 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 19:59:39,699 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 19:59:39,699 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 19:59:39,706 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 19:59:39,727 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 19:59:39,727 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 19:59:39,728 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 19:59:39,728 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 19:59:39,728 |-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 29302 (OK=29243 KO=59 ) > min response time 41 (OK=41 KO=60000 ) > max response time 60000 (OK=5909 KO=60000 ) > mean response time 171 (OK=50 KO=60000 ) > std deviation 2688 (OK=37 KO=0 ) > response time 50th percentile 49 (OK=49 KO=60000 ) > response time 75th percentile 51 (OK=51 KO=60000 ) > response time 95th percentile 60 (OK=59 KO=60000 ) > response time 99th percentile 86 (OK=85 KO=60000 ) > mean requests/sec 48.84 (OK=48.74 KO=0.1 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 29240 ( 99.79%) > 800 ms <= t < 1200 ms 2 ( 0.01%) > t >= 1200 ms 1 ( 0%) > failed 59 ( 0.2%) ---- Errors -------------------------------------------------------------------- > Request timeout to openam-perf-200m.forgeblocks.com/34.107.203 59 (100.0%) .37:443 after 60000 ms ================================================================================ Reports generated, please open the following file: file:///results/gatling/query-5/loadtest-20250328193550222/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 3.471 s [INFO] Finished at: 2025-03-28T19:59:42Z [INFO] ------------------------------------------------------------------------ 2025-03-28 19:59:42,100 INFO Gatling simulation run ended successfully 2025-03-28 19:59:42,101 INFO Creating archive query-5.tar.gz with report files 2025-03-28 19:59:42,166 INFO Archive /results/gatling/query-5/query-5.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 19:59:55] Reporting-query-5 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 19:59:55] Reporting-query-5 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 19:59:55,861 INFO 2025-03-28 19:59:55,861 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:55,861 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:56,140 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:56,140 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:56,140 DEBUG --- stdout --- 2025-03-28 19:59:56,140 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:56,140 DEBUG --- stderr --- 2025-03-28 19:59:56,140 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:56,141 INFO 2025-03-28 19:59:56,141 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-5/stderr.txt" ]; then echo "/results/orders/Reporting-query-5/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-query-5/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-5/stderr.txt file found" 2025-03-28 19:59:56,141 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:56,947 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:56,947 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:56,947 DEBUG --- stdout --- 2025-03-28 19:59:56,947 DEBUG /results/orders/Reporting-query-5/stderr.txt file found 2025-03-28 19:59:56,947 DEBUG --- stderr --- 2025-03-28 19:59:56,947 DEBUG 2025-03-28 19:59:56,947 INFO 2025-03-28 19:59:56,948 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-5/stderr.txt 2025-03-28 19:59:57,753 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:57,753 DEBUG --- stdout --- 2025-03-28 19:59:57,753 DEBUG 0 /results/orders/Reporting-query-5/stderr.txt 2025-03-28 19:59:57,753 DEBUG --- stderr --- 2025-03-28 19:59:57,753 DEBUG 2025-03-28 19:59:57,753 INFO - Download file (size 0k) source : /results/orders/Reporting-query-5/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-query-5/stderr.txt 2025-03-28 19:59:57,754 INFO 2025-03-28 19:59:57,754 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-5/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-query-5/stderr.txt 2025-03-28 19:59:57,754 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:58,517 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:58,518 DEBUG --- stdout --- 2025-03-28 19:59:58,518 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:58,518 DEBUG --- stderr --- 2025-03-28 19:59:58,518 DEBUG 2025-03-28 19:59:58,518 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-query-5/stderr.txt is small (0 lines), printing all lines: 2025-03-28 19:59:58,518 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 19:59:58] Reporting-query-5 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 19:59:58] Reporting-query-5 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/06_query-5/gatling/req_querypartial-650532761.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 19:59:58] Reporting-query-5 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 29302, "ok": 29243, "ko": 59 }, "minResponseTime": { "total": 41, "ok": 41, "ko": 60000 }, "maxResponseTime": { "total": 60000, "ok": 5909, "ko": 60000 }, "meanResponseTime": { "total": 171, "ok": 50, "ko": 60000 }, "standardDeviation": { "total": 2688, "ok": 37, "ko": 0 }, "percentiles1": { "total": 49, "ok": 49, "ko": 60000 }, "percentiles2": { "total": 51, "ok": 51, "ko": 60000 }, "percentiles3": { "total": 60, "ok": 59, "ko": 60000 }, "percentiles4": { "total": 86, "ok": 85, "ko": 60000 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 29240, "percentage": 99.78841034741656 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 2, "percentage": 0.0068254726639819805 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 1, "percentage": 0.0034127363319909902 }, "group4": { "name": "failed", "htmlName": "failed", "count": 59, "percentage": 0.20135144358746845 }, "meanNumberOfRequestsPerSecond": { "total": 48.836666666666666, "ok": 48.73833333333334, "ko": 0.09833333333333333 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 67, "ok": 67, "ko": 0 }, "maxResponseTime": { "total": 67, "ok": 67, "ko": 0 }, "meanResponseTime": { "total": 67, "ok": 67, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 67, "ok": 67, "ko": 0 }, "percentiles2": { "total": 67, "ok": 67, "ko": 0 }, "percentiles3": { "total": 67, "ok": 67, "ko": 0 }, "percentiles4": { "total": 67, "ok": 67, "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": 105, "ok": 105, "ko": 0 }, "maxResponseTime": { "total": 105, "ok": 105, "ko": 0 }, "meanResponseTime": { "total": 105, "ok": 105, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 105, "ok": 105, "ko": 0 }, "percentiles2": { "total": 105, "ok": 105, "ko": 0 }, "percentiles3": { "total": 105, "ok": 105, "ko": 0 }, "percentiles4": { "total": 105, "ok": 105, "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": 92, "ok": 92, "ko": 0 }, "maxResponseTime": { "total": 92, "ok": 92, "ko": 0 }, "meanResponseTime": { "total": 92, "ok": 92, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 92, "ok": 92, "ko": 0 }, "percentiles2": { "total": 92, "ok": 92, "ko": 0 }, "percentiles3": { "total": 92, "ok": 92, "ko": 0 }, "percentiles4": { "total": 92, "ok": 92, "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": 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": "User AccessToken PKCE stage", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 61, "ok": 61, "ko": 0 }, "maxResponseTime": { "total": 61, "ok": 61, "ko": 0 }, "meanResponseTime": { "total": 61, "ok": 61, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 61, "ok": 61, "ko": 0 }, "percentiles2": { "total": 61, "ok": 61, "ko": 0 }, "percentiles3": { "total": 61, "ok": 61, "ko": 0 }, "percentiles4": { "total": 61, "ok": 61, "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": 29297, "ok": 29238, "ko": 59 }, "minResponseTime": { "total": 41, "ok": 41, "ko": 60000 }, "maxResponseTime": { "total": 60000, "ok": 5909, "ko": 60000 }, "meanResponseTime": { "total": 171, "ok": 50, "ko": 60000 }, "standardDeviation": { "total": 2688, "ok": 37, "ko": 0 }, "percentiles1": { "total": 49, "ok": 49, "ko": 60000 }, "percentiles2": { "total": 51, "ok": 51, "ko": 60000 }, "percentiles3": { "total": 60, "ok": 59, "ko": 60000 }, "percentiles4": { "total": 86, "ok": 84, "ko": 60000 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 29235, "percentage": 99.78837423626993 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 2, "percentage": 0.006826637539679831 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 1, "percentage": 0.0034133187698399156 }, "group4": { "name": "failed", "htmlName": "failed", "count": 59, "percentage": 0.201385807420555 }, "meanNumberOfRequestsPerSecond": { "total": 48.82833333333333, "ok": 48.73, "ko": 0.09833333333333333 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 49, "avg_response_time": 171, "max_response_time": 5909, "min_response_time": 41, "num_requests": 29302, "num_requests_pass": 29243, "num_requests_percent_pass": 99.799 }, "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": 67, "total": 67 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 67, "total": 67 }, "minResponseTime": { "ko": 0, "ok": 67, "total": 67 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 67, "total": 67 }, "percentiles2": { "ko": 0, "ok": 67, "total": 67 }, "percentiles3": { "ko": 0, "ok": 67, "total": 67 }, "percentiles4": { "ko": 0, "ok": 67, "total": 67 }, "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": 105, "total": 105 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 105, "total": 105 }, "minResponseTime": { "ko": 0, "ok": 105, "total": 105 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 105, "total": 105 }, "percentiles2": { "ko": 0, "ok": 105, "total": 105 }, "percentiles3": { "ko": 0, "ok": 105, "total": 105 }, "percentiles4": { "ko": 0, "ok": 105, "total": 105 }, "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": 92, "total": 92 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 92, "total": 92 }, "minResponseTime": { "ko": 0, "ok": 92, "total": 92 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 92, "total": 92 }, "percentiles2": { "ko": 0, "ok": 92, "total": 92 }, "percentiles3": { "ko": 0, "ok": 92, "total": 92 }, "percentiles4": { "ko": 0, "ok": 92, "total": 92 }, "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 Authorize 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": 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": 61, "total": 61 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 61, "total": 61 }, "minResponseTime": { "ko": 0, "ok": 61, "total": 61 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 61, "total": 61 }, "percentiles2": { "ko": 0, "ok": 61, "total": 61 }, "percentiles3": { "ko": 0, "ok": 61, "total": 61 }, "percentiles4": { "ko": 0, "ok": 61, "total": 61 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 29235, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.78837423626993 }, "group2": { "count": 2, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.006826637539679831 }, "group3": { "count": 1, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0034133187698399156 }, "group4": { "count": 59, "htmlName": "failed", "name": "failed", "percentage": 0.201385807420555 }, "maxResponseTime": { "ko": 60000, "ok": 5909, "total": 60000 }, "meanNumberOfRequestsPerSecond": { "ko": 0.09833333333333333, "ok": 48.73, "total": 48.82833333333333 }, "meanResponseTime": { "ko": 60000, "ok": 50, "total": 171 }, "minResponseTime": { "ko": 60000, "ok": 41, "total": 41 }, "name": "queryPartial", "numberOfRequests": { "ko": 59, "ok": 29238, "total": 29297 }, "percentiles1": { "ko": 60000, "ok": 49, "total": 49 }, "percentiles2": { "ko": 60000, "ok": 51, "total": 51 }, "percentiles3": { "ko": 60000, "ok": 59, "total": 60 }, "percentiles4": { "ko": 60000, "ok": 84, "total": 86 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 37, "total": 2688 } } ], "global": { "group1": { "count": 29240, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.78841034741656 }, "group2": { "count": 2, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0068254726639819805 }, "group3": { "count": 1, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0034127363319909902 }, "group4": { "count": 59, "htmlName": "failed", "name": "failed", "percentage": 0.20135144358746845 }, "maxResponseTime": { "ko": 60000, "ok": 5909, "total": 60000 }, "meanNumberOfRequestsPerSecond": { "ko": 0.09833333333333333, "ok": 48.73833333333334, "total": 48.836666666666666 }, "meanResponseTime": { "ko": 60000, "ok": 50, "total": 171 }, "minResponseTime": { "ko": 60000, "ok": 41, "total": 41 }, "name": "All Requests", "numberOfRequests": { "ko": 59, "ok": 29243, "total": 29302 }, "percentiles1": { "ko": 60000, "ok": 49, "total": 49 }, "percentiles2": { "ko": 60000, "ok": 51, "total": 51 }, "percentiles3": { "ko": 60000, "ok": 59, "total": 60 }, "percentiles4": { "ko": 60000, "ok": 85, "total": 86 }, "standardDeviation": { "ko": 0, "ok": 37, "total": 2688 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 19:59:58] Reporting-query-5 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 19:59:58] Reporting-query-5 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/14_Reporting-query-5/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 19:59:58] Reporting-query-5 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped