--Task-- name: Reporting-query-4 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', 'Reporting-query-5'] preceding_task: None options: {'task_name': 'query-4'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 19:59:58 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 19:59:58 - 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-4/stdout.txt Pod stderr file : /results/orders/Reporting-query-4/stderr.txt Pod log : /results/orders/Reporting-query-4 ________________________________________________________________________________ [2025-03-28 19:59:58] Reporting-query-4 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/15_Reporting-query-4/order.json { "name": "Reporting-query-4", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/query-4\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"query-4.tar.gz\"", "out-dir": "/results/orders/Reporting-query-4" } ________________________________________________________________________________ [2025-03-28 19:59:58] Reporting-query-4 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 19:59:58,528 INFO 2025-03-28 19:59:58,528 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 19:59:58,528 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 19:59:58,743 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:58,744 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:58,744 DEBUG --- stdout --- 2025-03-28 19:59:58,744 DEBUG WAITING 2025-03-28 19:59:58,744 DEBUG --- stderr --- 2025-03-28 19:59:58,744 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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2025-03-28 19:59:58] Reporting-query-4 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 19:59:58,744 INFO 2025-03-28 19:59:58,744 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:58,744 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:59,023 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:59,023 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:59,023 DEBUG --- stdout --- 2025-03-28 19:59:59,023 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:59,023 DEBUG --- stderr --- 2025-03-28 19:59:59,023 DEBUG 2025-03-28 19:59:59,023 INFO 2025-03-28 19:59:59,023 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/15_Reporting-query-4/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 19:59:59,023 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 20:00:00,412 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:00,412 DEBUG --- stdout --- 2025-03-28 20:00:00,412 DEBUG 2025-03-28 20:00:00,412 DEBUG --- stderr --- 2025-03-28 20:00:00,412 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 20:00:00] Reporting-query-4 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 20:00:05,412 INFO 2025-03-28 20:00:05,413 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 20:00:05,413 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 20:00:05,630 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 20:00:10,847 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2025-03-28 20:00:10,847 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:10,847 DEBUG --- stdout --- 2025-03-28 20:00:10,847 DEBUG WAITING 2025-03-28 20:00:10,847 DEBUG --- stderr --- 2025-03-28 20:00:10,848 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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2025-03-28 20:00:10] Reporting-query-4 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 20:00:10,848 INFO 2025-03-28 20:00:10,848 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 20:00:10,848 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 20:00:11,129 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 20:00:11,129 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:11,129 DEBUG --- stdout --- 2025-03-28 20:00:11,129 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 20:00:11,129 DEBUG --- stderr --- 2025-03-28 20:00:11,129 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 20:00:11,130 INFO 2025-03-28 20:00:11,130 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-4/query-4.tar.gz" ]; then echo "/results/gatling/query-4/query-4.tar.gz file found"; exit 0; else echo "/results/gatling/query-4/query-4.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/query-4/query-4.tar.gz file found" 2025-03-28 20:00:11,130 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 20:00:11,974 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 20:00:11,974 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:11,974 DEBUG --- stdout --- 2025-03-28 20:00:11,974 DEBUG /results/gatling/query-4/query-4.tar.gz file found 2025-03-28 20:00:11,974 DEBUG --- stderr --- 2025-03-28 20:00:11,975 DEBUG 2025-03-28 20:00:11,975 INFO 2025-03-28 20:00:11,975 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-4/query-4.tar.gz 2025-03-28 20:00:12,781 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 20:00:12,781 DEBUG --- stdout --- 2025-03-28 20:00:12,781 DEBUG 264 /results/gatling/query-4/query-4.tar.gz 2025-03-28 20:00:12,781 DEBUG --- stderr --- 2025-03-28 20:00:12,781 DEBUG 2025-03-28 20:00:12,781 INFO - Download file (size 264k) source : /results/gatling/query-4/query-4.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-4.tar.gz 2025-03-28 20:00:12,782 INFO 2025-03-28 20:00:12,782 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/query-4/query-4.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-4.tar.gz 2025-03-28 20:00:12,782 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 20:00:13,779 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:13,779 DEBUG --- stdout --- 2025-03-28 20:00:13,779 DEBUG tar: Removing leading `/' from member names 2025-03-28 20:00:13,779 DEBUG --- stderr --- 2025-03-28 20:00:13,779 DEBUG ________________________________________________________________________________ [2025-03-28 20:00:13] Reporting-query-4 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 20:00:13,780 INFO 2025-03-28 20:00:13,780 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-4.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/07_query-4/gatling 2025-03-28 20:00:13,792 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 20:00:13,793 DEBUG --- stdout --- 2025-03-28 20:00:13,793 DEBUG 2025-03-28 20:00:13,793 DEBUG --- stderr --- 2025-03-28 20:00:13,793 DEBUG 2025-03-28 20:00:13,793 INFO ________________________________________________________________________________ [2025-03-28 20:00:13] Reporting-query-4 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 20:00:13,793 INFO 2025-03-28 20:00:13,793 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 20:00:13,793 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 20:00:14,071 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 20:00:14,071 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:14,071 DEBUG --- stdout --- 2025-03-28 20:00:14,071 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 20:00:14,071 DEBUG --- stderr --- 2025-03-28 20:00:14,071 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 20:00:14,072 INFO 2025-03-28 20:00:14,072 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-4/rc.txt" ]; then echo "/results/orders/Reporting-query-4/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-query-4/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-4/rc.txt file found" 2025-03-28 20:00:14,072 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 20:00:14,874 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 20:00:14,874 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:14,874 DEBUG --- stdout --- 2025-03-28 20:00:14,874 DEBUG /results/orders/Reporting-query-4/rc.txt file found 2025-03-28 20:00:14,874 DEBUG --- stderr --- 2025-03-28 20:00:14,874 DEBUG 2025-03-28 20:00:14,874 INFO 2025-03-28 20:00:14,875 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-4/rc.txt 2025-03-28 20:00:15,670 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 20:00:15,670 DEBUG --- stdout --- 2025-03-28 20:00:15,670 DEBUG 4 /results/orders/Reporting-query-4/rc.txt 2025-03-28 20:00:15,670 DEBUG --- stderr --- 2025-03-28 20:00:15,670 DEBUG 2025-03-28 20:00:15,670 INFO - Download file (size 4k) source : /results/orders/Reporting-query-4/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-query-4/rc.txt 2025-03-28 20:00:15,671 INFO 2025-03-28 20:00:15,671 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-4/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-query-4/rc.txt 2025-03-28 20:00:15,671 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 20:00:16,392 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:16,392 DEBUG --- stdout --- 2025-03-28 20:00:16,392 DEBUG tar: Removing leading `/' from member names 2025-03-28 20:00:16,392 DEBUG --- stderr --- 2025-03-28 20:00:16,392 DEBUG 2025-03-28 20:00:16,392 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-query-4/rc.txt is small (1 lines), printing all lines: 2025-03-28 20:00:16,392 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 20:00:16] Reporting-query-4 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 20:00:16] Reporting-query-4 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 20:00:16,631 INFO 2025-03-28 20:00:16,631 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 20:00:16,631 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 20:00:16,910 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 20:00:16,910 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:16,910 DEBUG --- stdout --- 2025-03-28 20:00:16,910 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 20:00:16,910 DEBUG --- stderr --- 2025-03-28 20:00:16,910 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 20:00:16,911 INFO 2025-03-28 20:00:16,911 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-4/stdout.txt" ]; then echo "/results/orders/Reporting-query-4/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-query-4/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-4/stdout.txt file found" 2025-03-28 20:00:16,911 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 20:00:17,721 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 20:00:17,721 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:17,721 DEBUG --- stdout --- 2025-03-28 20:00:17,721 DEBUG /results/orders/Reporting-query-4/stdout.txt file found 2025-03-28 20:00:17,721 DEBUG --- stderr --- 2025-03-28 20:00:17,721 DEBUG 2025-03-28 20:00:17,721 INFO 2025-03-28 20:00:17,721 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-4/stdout.txt 2025-03-28 20:00:18,518 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 20:00:18,518 DEBUG --- stdout --- 2025-03-28 20:00:18,518 DEBUG 8 /results/orders/Reporting-query-4/stdout.txt 2025-03-28 20:00:18,518 DEBUG --- stderr --- 2025-03-28 20:00:18,518 DEBUG 2025-03-28 20:00:18,518 INFO - Download file (size 8k) source : /results/orders/Reporting-query-4/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-query-4/stdout.txt 2025-03-28 20:00:18,519 INFO 2025-03-28 20:00:18,519 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-4/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-query-4/stdout.txt 2025-03-28 20:00:18,519 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 20:00:19,278 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:19,278 DEBUG --- stdout --- 2025-03-28 20:00:19,278 DEBUG tar: Removing leading `/' from member names 2025-03-28 20:00:19,278 DEBUG --- stderr --- 2025-03-28 20:00:19,278 DEBUG 2025-03-28 20:00:19,279 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-query-4/stdout.txt is small (94 lines), printing all lines: 2025-03-28 20:00:19,279 INFO ----- output ----- 2025-03-28 20:00:01,255 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpkno5gu35 2025-03-28 20:00:01,257 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/query-4/loadtest-20250328194621249" -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/tmpkno5gu35/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/tmpkno5gu35/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 --- 20:00:03,099 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 20:00:03,101 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 20:00:03,101 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 20:00:03,102 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 20:00:03,107 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 20:00:03,107 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 20:00:03,115 |-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 20:00:03,115 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 20:00:03,115 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 20:00:03,116 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 20:00:03,118 |-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] 20:00:03,123 |-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 20:00:03,168 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 20:00:03,168 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 20:00:03,209 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 20:00:03,209 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 20:00:03,215 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 20:00:03,237 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 20:00:03,237 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 20:00:03,238 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 20:00:03,238 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 20:00:03,238 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 123 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 28869 (OK=12062 KO=16807 ) > min response time 37 (OK=42 KO=37 ) > max response time 60001 (OK=58161 KO=60001 ) > mean response time 10685 (OK=11465 KO=10126 ) > std deviation 16397 (OK=15963 KO=16679 ) > response time 50th percentile 53 (OK=58 KO=48 ) > response time 75th percentile 20146 (OK=23683 KO=16053 ) > response time 95th percentile 45654 (OK=44725 KO=47648 ) > response time 99th percentile 60000 (OK=48979 KO=60000 ) > mean requests/sec 48.12 (OK=20.1 KO=28.01 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 7066 ( 24.48%) > 800 ms <= t < 1200 ms 10 ( 0.03%) > t >= 1200 ms 4986 ( 17.27%) > failed 16807 ( 58.22%) ---- Errors -------------------------------------------------------------------- > status.find.in(200), but actually found 500 11294 (67.20%) > status.find.in(200), but actually found 503 3720 (22.13%) > status.find.in(200), but actually found 502 1103 ( 6.56%) > Request timeout to openam-perf-200m.forgeblocks.com/34.107.203 582 ( 3.46%) .37:443 after 60000 ms > status.find.in(200), but actually found 404 108 ( 0.64%) ================================================================================ Reports generated, please open the following file: file:///results/gatling/query-4/loadtest-20250328194621249/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 3.414 s [INFO] Finished at: 2025-03-28T20:00:05Z [INFO] ------------------------------------------------------------------------ 2025-03-28 20:00:05,540 INFO Gatling simulation run ended successfully 2025-03-28 20:00:05,540 INFO Creating archive query-4.tar.gz with report files 2025-03-28 20:00:05,603 INFO Archive /results/gatling/query-4/query-4.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 20:00:19] Reporting-query-4 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 20:00:19] Reporting-query-4 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 20:00:19,280 INFO 2025-03-28 20:00:19,280 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 20:00:19,280 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 20:00:19,558 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 20:00:19,558 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:19,558 DEBUG --- stdout --- 2025-03-28 20:00:19,558 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 20:00:19,558 DEBUG --- stderr --- 2025-03-28 20:00:19,558 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 20:00:19,559 INFO 2025-03-28 20:00:19,559 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-4/stderr.txt" ]; then echo "/results/orders/Reporting-query-4/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-query-4/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-4/stderr.txt file found" 2025-03-28 20:00:19,559 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 20:00:20,363 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 20:00:20,364 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:20,364 DEBUG --- stdout --- 2025-03-28 20:00:20,364 DEBUG /results/orders/Reporting-query-4/stderr.txt file found 2025-03-28 20:00:20,364 DEBUG --- stderr --- 2025-03-28 20:00:20,364 DEBUG 2025-03-28 20:00:20,364 INFO 2025-03-28 20:00:20,364 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-4/stderr.txt 2025-03-28 20:00:21,160 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 20:00:21,160 DEBUG --- stdout --- 2025-03-28 20:00:21,160 DEBUG 0 /results/orders/Reporting-query-4/stderr.txt 2025-03-28 20:00:21,160 DEBUG --- stderr --- 2025-03-28 20:00:21,160 DEBUG 2025-03-28 20:00:21,160 INFO - Download file (size 0k) source : /results/orders/Reporting-query-4/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-query-4/stderr.txt 2025-03-28 20:00:21,161 INFO 2025-03-28 20:00:21,161 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-4/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-query-4/stderr.txt 2025-03-28 20:00:21,161 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 20:00:21,892 INFO [loop_until]: OK (rc = 0) 2025-03-28 20:00:21,892 DEBUG --- stdout --- 2025-03-28 20:00:21,892 DEBUG tar: Removing leading `/' from member names 2025-03-28 20:00:21,892 DEBUG --- stderr --- 2025-03-28 20:00:21,892 DEBUG 2025-03-28 20:00:21,893 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-query-4/stderr.txt is small (0 lines), printing all lines: 2025-03-28 20:00:21,893 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 20:00:21] Reporting-query-4 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 20:00:21] Reporting-query-4 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/07_query-4/gatling/req_querypartial-650532761.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 20:00:21] Reporting-query-4 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 28869, "ok": 12062, "ko": 16807 }, "minResponseTime": { "total": 37, "ok": 42, "ko": 37 }, "maxResponseTime": { "total": 60001, "ok": 58161, "ko": 60001 }, "meanResponseTime": { "total": 10685, "ok": 11465, "ko": 10126 }, "standardDeviation": { "total": 16397, "ok": 15963, "ko": 16679 }, "percentiles1": { "total": 53, "ok": 58, "ko": 48 }, "percentiles2": { "total": 20146, "ok": 23683, "ko": 16053 }, "percentiles3": { "total": 45654, "ok": 44725, "ko": 47648 }, "percentiles4": { "total": 60000, "ok": 48979, "ko": 60000 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 7066, "percentage": 24.47608161003152 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 10, "percentage": 0.034639232394610135 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 4986, "percentage": 17.271121271952612 }, "group4": { "name": "failed", "htmlName": "failed", "count": 16807, "percentage": 58.21815788562126 }, "meanNumberOfRequestsPerSecond": { "total": 48.115, "ok": 20.10333333333333, "ko": 28.011666666666667 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 73, "ok": 73, "ko": 0 }, "maxResponseTime": { "total": 73, "ok": 73, "ko": 0 }, "meanResponseTime": { "total": 73, "ok": 73, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 73, "ok": 73, "ko": 0 }, "percentiles2": { "total": 73, "ok": 73, "ko": 0 }, "percentiles3": { "total": 73, "ok": 73, "ko": 0 }, "percentiles4": { "total": 73, "ok": 73, "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": 142, "ok": 142, "ko": 0 }, "maxResponseTime": { "total": 142, "ok": 142, "ko": 0 }, "meanResponseTime": { "total": 142, "ok": 142, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 142, "ok": 142, "ko": 0 }, "percentiles2": { "total": 142, "ok": 142, "ko": 0 }, "percentiles3": { "total": 142, "ok": 142, "ko": 0 }, "percentiles4": { "total": 142, "ok": 142, "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": 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": "User Authorize PKCE stage", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 91, "ok": 91, "ko": 0 }, "maxResponseTime": { "total": 91, "ok": 91, "ko": 0 }, "meanResponseTime": { "total": 91, "ok": 91, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 91, "ok": 91, "ko": 0 }, "percentiles2": { "total": 91, "ok": 91, "ko": 0 }, "percentiles3": { "total": 91, "ok": 91, "ko": 0 }, "percentiles4": { "total": 91, "ok": 91, "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": 63, "ok": 63, "ko": 0 }, "maxResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "meanResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 63, "ok": 63, "ko": 0 }, "percentiles2": { "total": 63, "ok": 63, "ko": 0 }, "percentiles3": { "total": 63, "ok": 63, "ko": 0 }, "percentiles4": { "total": 63, "ok": 63, "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": 28864, "ok": 12057, "ko": 16807 }, "minResponseTime": { "total": 37, "ok": 42, "ko": 37 }, "maxResponseTime": { "total": 60001, "ok": 58161, "ko": 60001 }, "meanResponseTime": { "total": 10687, "ok": 11469, "ko": 10126 }, "standardDeviation": { "total": 16398, "ok": 15965, "ko": 16679 }, "percentiles1": { "total": 53, "ok": 58, "ko": 48 }, "percentiles2": { "total": 20151, "ok": 23692, "ko": 16053 }, "percentiles3": { "total": 45655, "ok": 44728, "ko": 47648 }, "percentiles4": { "total": 60000, "ok": 48980, "ko": 60000 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 7061, "percentage": 24.462998891352548 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 10, "percentage": 0.03464523281596452 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 4986, "percentage": 17.27411308203991 }, "group4": { "name": "failed", "htmlName": "failed", "count": 16807, "percentage": 58.22824279379157 }, "meanNumberOfRequestsPerSecond": { "total": 48.10666666666667, "ok": 20.095, "ko": 28.011666666666667 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 48, "avg_response_time": 10685, "max_response_time": 58161, "min_response_time": 42, "num_requests": 28869, "num_requests_pass": 12062, "num_requests_percent_pass": 41.782 }, "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": 73, "total": 73 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 73, "total": 73 }, "minResponseTime": { "ko": 0, "ok": 73, "total": 73 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 73, "total": 73 }, "percentiles2": { "ko": 0, "ok": 73, "total": 73 }, "percentiles3": { "ko": 0, "ok": 73, "total": 73 }, "percentiles4": { "ko": 0, "ok": 73, "total": 73 }, "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": 142, "total": 142 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 142, "total": 142 }, "minResponseTime": { "ko": 0, "ok": 142, "total": 142 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 142, "total": 142 }, "percentiles2": { "ko": 0, "ok": 142, "total": 142 }, "percentiles3": { "ko": 0, "ok": 142, "total": 142 }, "percentiles4": { "ko": 0, "ok": 142, "total": 142 }, "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": "Skip 2FA", "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": 91, "total": 91 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 91, "total": 91 }, "minResponseTime": { "ko": 0, "ok": 91, "total": 91 }, "name": "User Authorize PKCE stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 91, "total": 91 }, "percentiles2": { "ko": 0, "ok": 91, "total": 91 }, "percentiles3": { "ko": 0, "ok": 91, "total": 91 }, "percentiles4": { "ko": 0, "ok": 91, "total": 91 }, "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": 63, "total": 63 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "minResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 63, "total": 63 }, "percentiles2": { "ko": 0, "ok": 63, "total": 63 }, "percentiles3": { "ko": 0, "ok": 63, "total": 63 }, "percentiles4": { "ko": 0, "ok": 63, "total": 63 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 7061, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 24.462998891352548 }, "group2": { "count": 10, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.03464523281596452 }, "group3": { "count": 4986, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 17.27411308203991 }, "group4": { "count": 16807, "htmlName": "failed", "name": "failed", "percentage": 58.22824279379157 }, "maxResponseTime": { "ko": 60001, "ok": 58161, "total": 60001 }, "meanNumberOfRequestsPerSecond": { "ko": 28.011666666666667, "ok": 20.095, "total": 48.10666666666667 }, "meanResponseTime": { "ko": 10126, "ok": 11469, "total": 10687 }, "minResponseTime": { "ko": 37, "ok": 42, "total": 37 }, "name": "queryPartial", "numberOfRequests": { "ko": 16807, "ok": 12057, "total": 28864 }, "percentiles1": { "ko": 48, "ok": 58, "total": 53 }, "percentiles2": { "ko": 16053, "ok": 23692, "total": 20151 }, "percentiles3": { "ko": 47648, "ok": 44728, "total": 45655 }, "percentiles4": { "ko": 60000, "ok": 48980, "total": 60000 }, "spikes": [], "standardDeviation": { "ko": 16679, "ok": 15965, "total": 16398 } } ], "global": { "group1": { "count": 7066, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 24.47608161003152 }, "group2": { "count": 10, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.034639232394610135 }, "group3": { "count": 4986, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 17.271121271952612 }, "group4": { "count": 16807, "htmlName": "failed", "name": "failed", "percentage": 58.21815788562126 }, "maxResponseTime": { "ko": 60001, "ok": 58161, "total": 60001 }, "meanNumberOfRequestsPerSecond": { "ko": 28.011666666666667, "ok": 20.10333333333333, "total": 48.115 }, "meanResponseTime": { "ko": 10126, "ok": 11465, "total": 10685 }, "minResponseTime": { "ko": 37, "ok": 42, "total": 37 }, "name": "All Requests", "numberOfRequests": { "ko": 16807, "ok": 12062, "total": 28869 }, "percentiles1": { "ko": 48, "ok": 58, "total": 53 }, "percentiles2": { "ko": 16053, "ok": 23683, "total": 20146 }, "percentiles3": { "ko": 47648, "ok": 44725, "total": 45654 }, "percentiles4": { "ko": 60000, "ok": 48979, "total": 60000 }, "standardDeviation": { "ko": 16679, "ok": 15963, "total": 16397 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 20:00:21] Reporting-query-4 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 20:00:21] Reporting-query-4 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/15_Reporting-query-4/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 20:00:21] Reporting-query-4 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped