--Task-- name: Reporting-query-7 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'] preceding_task: None options: {'task_name': 'query-7'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 19:58:48 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 19:58:48 - 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-7/stdout.txt Pod stderr file : /results/orders/Reporting-query-7/stderr.txt Pod log : /results/orders/Reporting-query-7 ________________________________________________________________________________ [2025-03-28 19:58:48] Reporting-query-7 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/12_Reporting-query-7/order.json { "name": "Reporting-query-7", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/query-7\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"query-7.tar.gz\"", "out-dir": "/results/orders/Reporting-query-7" } ________________________________________________________________________________ [2025-03-28 19:58:48] Reporting-query-7 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 19:58:48,165 INFO 2025-03-28 19:58:48,165 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 19:58:48,165 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 19:58:48,380 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:58:48,380 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:58:48,380 DEBUG --- stdout --- 2025-03-28 19:58:48,380 DEBUG WAITING 2025-03-28 19:58:48,380 DEBUG --- stderr --- 2025-03-28 19:58:48,380 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:58:48] Reporting-query-7 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 19:58:48,381 INFO 2025-03-28 19:58:48,381 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:58:48,381 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:58:48,659 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:58:48,659 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:58:48,659 DEBUG --- stdout --- 2025-03-28 19:58:48,659 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:58:48,659 DEBUG --- stderr --- 2025-03-28 19:58:48,659 DEBUG 2025-03-28 19:58:48,659 INFO 2025-03-28 19:58:48,659 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/12_Reporting-query-7/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 19:58:48,659 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:58:50,125 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:58:50,125 DEBUG --- stdout --- 2025-03-28 19:58:50,125 DEBUG 2025-03-28 19:58:50,125 DEBUG --- stderr --- 2025-03-28 19:58:50,125 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 19:58:50] Reporting-query-7 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 19:58:55,126 INFO 2025-03-28 19:58:55,126 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 19:58:55,126 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 19:58:55,439 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 19:59:00,654 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2025-03-28 19:59:00,654 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:00,654 DEBUG --- stdout --- 2025-03-28 19:59:00,654 DEBUG WAITING 2025-03-28 19:59:00,654 DEBUG --- stderr --- 2025-03-28 19:59:00,654 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:00] Reporting-query-7 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 19:59:00,655 INFO 2025-03-28 19:59:00,655 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:00,655 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:00,955 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:00,955 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:00,955 DEBUG --- stdout --- 2025-03-28 19:59:00,955 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:00,955 DEBUG --- stderr --- 2025-03-28 19:59:00,955 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:00,956 INFO 2025-03-28 19:59:00,956 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-7/query-7.tar.gz" ]; then echo "/results/gatling/query-7/query-7.tar.gz file found"; exit 0; else echo "/results/gatling/query-7/query-7.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/query-7/query-7.tar.gz file found" 2025-03-28 19:59:00,956 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:01,767 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:01,767 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:01,767 DEBUG --- stdout --- 2025-03-28 19:59:01,767 DEBUG /results/gatling/query-7/query-7.tar.gz file found 2025-03-28 19:59:01,767 DEBUG --- stderr --- 2025-03-28 19:59:01,767 DEBUG 2025-03-28 19:59:01,767 INFO 2025-03-28 19:59:01,767 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-7/query-7.tar.gz 2025-03-28 19:59:02,573 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:02,573 DEBUG --- stdout --- 2025-03-28 19:59:02,573 DEBUG 248 /results/gatling/query-7/query-7.tar.gz 2025-03-28 19:59:02,573 DEBUG --- stderr --- 2025-03-28 19:59:02,573 DEBUG 2025-03-28 19:59:02,573 INFO - Download file (size 248k) source : /results/gatling/query-7/query-7.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-7.tar.gz 2025-03-28 19:59:02,574 INFO 2025-03-28 19:59:02,574 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/query-7/query-7.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-7.tar.gz 2025-03-28 19:59:02,574 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:03,570 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:03,570 DEBUG --- stdout --- 2025-03-28 19:59:03,570 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:03,570 DEBUG --- stderr --- 2025-03-28 19:59:03,570 DEBUG ________________________________________________________________________________ [2025-03-28 19:59:03] Reporting-query-7 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 19:59:03,570 INFO 2025-03-28 19:59:03,571 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/query-7.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/04_query-7/gatling 2025-03-28 19:59:03,583 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:03,583 DEBUG --- stdout --- 2025-03-28 19:59:03,583 DEBUG 2025-03-28 19:59:03,583 DEBUG --- stderr --- 2025-03-28 19:59:03,583 DEBUG 2025-03-28 19:59:03,584 INFO ________________________________________________________________________________ [2025-03-28 19:59:03] Reporting-query-7 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 19:59:03,584 INFO 2025-03-28 19:59:03,584 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:03,584 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:03,862 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:03,862 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:03,862 DEBUG --- stdout --- 2025-03-28 19:59:03,863 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:03,863 DEBUG --- stderr --- 2025-03-28 19:59:03,863 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:03,863 INFO 2025-03-28 19:59:03,864 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-7/rc.txt" ]; then echo "/results/orders/Reporting-query-7/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-query-7/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-7/rc.txt file found" 2025-03-28 19:59:03,864 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:04,658 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:04,658 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:04,658 DEBUG --- stdout --- 2025-03-28 19:59:04,658 DEBUG /results/orders/Reporting-query-7/rc.txt file found 2025-03-28 19:59:04,658 DEBUG --- stderr --- 2025-03-28 19:59:04,658 DEBUG 2025-03-28 19:59:04,658 INFO 2025-03-28 19:59:04,658 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-7/rc.txt 2025-03-28 19:59:05,451 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:05,451 DEBUG --- stdout --- 2025-03-28 19:59:05,451 DEBUG 4 /results/orders/Reporting-query-7/rc.txt 2025-03-28 19:59:05,451 DEBUG --- stderr --- 2025-03-28 19:59:05,451 DEBUG 2025-03-28 19:59:05,451 INFO - Download file (size 4k) source : /results/orders/Reporting-query-7/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-query-7/rc.txt 2025-03-28 19:59:05,452 INFO 2025-03-28 19:59:05,452 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-7/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-query-7/rc.txt 2025-03-28 19:59:05,452 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:06,177 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:06,177 DEBUG --- stdout --- 2025-03-28 19:59:06,177 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:06,177 DEBUG --- stderr --- 2025-03-28 19:59:06,177 DEBUG 2025-03-28 19:59:06,177 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-query-7/rc.txt is small (1 lines), printing all lines: 2025-03-28 19:59:06,177 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 19:59:06] Reporting-query-7 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:06] Reporting-query-7 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 19:59:06,415 INFO 2025-03-28 19:59:06,415 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:06,415 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:06,692 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:06,692 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:06,692 DEBUG --- stdout --- 2025-03-28 19:59:06,692 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:06,692 DEBUG --- stderr --- 2025-03-28 19:59:06,692 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:06,693 INFO 2025-03-28 19:59:06,693 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-7/stdout.txt" ]; then echo "/results/orders/Reporting-query-7/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-query-7/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-7/stdout.txt file found" 2025-03-28 19:59:06,693 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:07,487 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:07,487 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:07,487 DEBUG --- stdout --- 2025-03-28 19:59:07,487 DEBUG /results/orders/Reporting-query-7/stdout.txt file found 2025-03-28 19:59:07,487 DEBUG --- stderr --- 2025-03-28 19:59:07,487 DEBUG 2025-03-28 19:59:07,487 INFO 2025-03-28 19:59:07,488 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-7/stdout.txt 2025-03-28 19:59:08,288 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:08,288 DEBUG --- stdout --- 2025-03-28 19:59:08,288 DEBUG 8 /results/orders/Reporting-query-7/stdout.txt 2025-03-28 19:59:08,288 DEBUG --- stderr --- 2025-03-28 19:59:08,288 DEBUG 2025-03-28 19:59:08,288 INFO - Download file (size 8k) source : /results/orders/Reporting-query-7/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-query-7/stdout.txt 2025-03-28 19:59:08,289 INFO 2025-03-28 19:59:08,289 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-7/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-query-7/stdout.txt 2025-03-28 19:59:08,289 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:09,017 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:09,018 DEBUG --- stdout --- 2025-03-28 19:59:09,018 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:09,018 DEBUG --- stderr --- 2025-03-28 19:59:09,018 DEBUG 2025-03-28 19:59:09,018 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-query-7/stdout.txt is small (89 lines), printing all lines: 2025-03-28 19:59:09,018 INFO ----- output ----- 2025-03-28 19:58:50,734 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp67ut7fb7 2025-03-28 19:58:50,736 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/query-7/loadtest-20250328191446998" -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/tmp67ut7fb7/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/tmp67ut7fb7/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:58:52,558 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 19:58:52,559 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 19:58:52,559 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 19:58:52,560 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 19:58:52,565 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 19:58:52,565 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 19:58:52,573 |-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:58:52,573 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 19:58:52,573 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 19:58:52,574 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 19:58:52,576 |-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:58:52,581 |-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:58:52,625 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 19:58:52,625 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 19:58:52,664 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 19:58:52,664 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 19:58:52,671 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 19:58:52,691 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 19:58:52,692 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 19:58:52,692 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 19:58:52,692 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 19:58:52,692 |-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 29024 (OK=29023 KO=1 ) > min response time 40 (OK=40 KO=205 ) > max response time 7372 (OK=7372 KO=205 ) > mean response time 48 (OK=48 KO=205 ) > std deviation 50 (OK=50 KO=0 ) > response time 50th percentile 47 (OK=47 KO=205 ) > response time 75th percentile 49 (OK=49 KO=205 ) > response time 95th percentile 52 (OK=52 KO=205 ) > response time 99th percentile 58 (OK=58 KO=205 ) > mean requests/sec 48.37 (OK=48.37 KO=0 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 29015 ( 99.97%) > 800 ms <= t < 1200 ms 5 ( 0.02%) > t >= 1200 ms 3 ( 0.01%) > failed 1 ( 0%) ---- Errors -------------------------------------------------------------------- > status.find.in(200), but actually found 503 1 (100.0%) ================================================================================ Reports generated, please open the following file: file:///results/gatling/query-7/loadtest-20250328191446998/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 3.327 s [INFO] Finished at: 2025-03-28T19:58:54Z [INFO] ------------------------------------------------------------------------ 2025-03-28 19:58:54,942 INFO Gatling simulation run ended successfully 2025-03-28 19:58:54,942 INFO Creating archive query-7.tar.gz with report files 2025-03-28 19:58:55,006 INFO Archive /results/gatling/query-7/query-7.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 19:59:09] Reporting-query-7 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 19:59:09] Reporting-query-7 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 19:59:09,019 INFO 2025-03-28 19:59:09,019 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:09,019 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 19:59:09,296 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 19:59:09,296 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:09,296 DEBUG --- stdout --- 2025-03-28 19:59:09,296 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 19:59:09,296 DEBUG --- stderr --- 2025-03-28 19:59:09,296 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 19:59:09,297 INFO 2025-03-28 19:59:09,297 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-7/stderr.txt" ]; then echo "/results/orders/Reporting-query-7/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-query-7/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-query-7/stderr.txt file found" 2025-03-28 19:59:09,297 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 19:59:10,099 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 19:59:10,100 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:10,100 DEBUG --- stdout --- 2025-03-28 19:59:10,100 DEBUG /results/orders/Reporting-query-7/stderr.txt file found 2025-03-28 19:59:10,100 DEBUG --- stderr --- 2025-03-28 19:59:10,100 DEBUG 2025-03-28 19:59:10,100 INFO 2025-03-28 19:59:10,100 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-7/stderr.txt 2025-03-28 19:59:10,971 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 19:59:10,971 DEBUG --- stdout --- 2025-03-28 19:59:10,972 DEBUG 0 /results/orders/Reporting-query-7/stderr.txt 2025-03-28 19:59:10,972 DEBUG --- stderr --- 2025-03-28 19:59:10,972 DEBUG 2025-03-28 19:59:10,972 INFO - Download file (size 0k) source : /results/orders/Reporting-query-7/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-query-7/stderr.txt 2025-03-28 19:59:10,972 INFO 2025-03-28 19:59:10,972 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-7/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-query-7/stderr.txt 2025-03-28 19:59:10,972 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 19:59:11,709 INFO [loop_until]: OK (rc = 0) 2025-03-28 19:59:11,709 DEBUG --- stdout --- 2025-03-28 19:59:11,709 DEBUG tar: Removing leading `/' from member names 2025-03-28 19:59:11,709 DEBUG --- stderr --- 2025-03-28 19:59:11,709 DEBUG 2025-03-28 19:59:11,709 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-query-7/stderr.txt is small (0 lines), printing all lines: 2025-03-28 19:59:11,709 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 19:59:11] Reporting-query-7 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 19:59:11] Reporting-query-7 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/04_query-7/gatling/req_querypartial-650532761.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 19:59:11] Reporting-query-7 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 29024, "ok": 29023, "ko": 1 }, "minResponseTime": { "total": 40, "ok": 40, "ko": 205 }, "maxResponseTime": { "total": 7372, "ok": 7372, "ko": 205 }, "meanResponseTime": { "total": 48, "ok": 48, "ko": 205 }, "standardDeviation": { "total": 50, "ok": 50, "ko": 0 }, "percentiles1": { "total": 47, "ok": 47, "ko": 205 }, "percentiles2": { "total": 49, "ok": 49, "ko": 205 }, "percentiles3": { "total": 52, "ok": 52, "ko": 205 }, "percentiles4": { "total": 58, "ok": 58, "ko": 205 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 29015, "percentage": 99.96899117971334 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 5, "percentage": 0.017227122381477396 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 3, "percentage": 0.010336273428886439 }, "group4": { "name": "failed", "htmlName": "failed", "count": 1, "percentage": 0.0034454244762954795 }, "meanNumberOfRequestsPerSecond": { "total": 48.373333333333335, "ok": 48.37166666666667, "ko": 0.0016666666666666668 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 69, "ok": 69, "ko": 0 }, "maxResponseTime": { "total": 69, "ok": 69, "ko": 0 }, "meanResponseTime": { "total": 69, "ok": 69, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 69, "ok": 69, "ko": 0 }, "percentiles2": { "total": 69, "ok": 69, "ko": 0 }, "percentiles3": { "total": 69, "ok": 69, "ko": 0 }, "percentiles4": { "total": 69, "ok": 69, "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": 127, "ok": 127, "ko": 0 }, "maxResponseTime": { "total": 127, "ok": 127, "ko": 0 }, "meanResponseTime": { "total": 127, "ok": 127, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 127, "ok": 127, "ko": 0 }, "percentiles2": { "total": 127, "ok": 127, "ko": 0 }, "percentiles3": { "total": 127, "ok": 127, "ko": 0 }, "percentiles4": { "total": 127, "ok": 127, "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": 90, "ok": 90, "ko": 0 }, "maxResponseTime": { "total": 90, "ok": 90, "ko": 0 }, "meanResponseTime": { "total": 90, "ok": 90, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 90, "ok": 90, "ko": 0 }, "percentiles2": { "total": 90, "ok": 90, "ko": 0 }, "percentiles3": { "total": 90, "ok": 90, "ko": 0 }, "percentiles4": { "total": 90, "ok": 90, "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": 68, "ok": 68, "ko": 0 }, "maxResponseTime": { "total": 68, "ok": 68, "ko": 0 }, "meanResponseTime": { "total": 68, "ok": 68, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 68, "ok": 68, "ko": 0 }, "percentiles2": { "total": 68, "ok": 68, "ko": 0 }, "percentiles3": { "total": 68, "ok": 68, "ko": 0 }, "percentiles4": { "total": 68, "ok": 68, "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": 29019, "ok": 29018, "ko": 1 }, "minResponseTime": { "total": 40, "ok": 40, "ko": 205 }, "maxResponseTime": { "total": 7372, "ok": 7372, "ko": 205 }, "meanResponseTime": { "total": 48, "ok": 48, "ko": 205 }, "standardDeviation": { "total": 50, "ok": 50, "ko": 0 }, "percentiles1": { "total": 47, "ok": 47, "ko": 205 }, "percentiles2": { "total": 49, "ok": 49, "ko": 205 }, "percentiles3": { "total": 52, "ok": 52, "ko": 205 }, "percentiles4": { "total": 57, "ok": 57, "ko": 205 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 29010, "percentage": 99.9689858368655 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 5, "percentage": 0.017230090630276713 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 3, "percentage": 0.010338054378166029 }, "group4": { "name": "failed", "htmlName": "failed", "count": 1, "percentage": 0.0034460181260553436 }, "meanNumberOfRequestsPerSecond": { "total": 48.365, "ok": 48.36333333333334, "ko": 0.0016666666666666668 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 48, "avg_response_time": 48, "max_response_time": 7372, "min_response_time": 40, "num_requests": 29024, "num_requests_pass": 29023, "num_requests_percent_pass": 99.997 }, "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": 69, "total": 69 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 69, "total": 69 }, "minResponseTime": { "ko": 0, "ok": 69, "total": 69 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 69, "total": 69 }, "percentiles2": { "ko": 0, "ok": 69, "total": 69 }, "percentiles3": { "ko": 0, "ok": 69, "total": 69 }, "percentiles4": { "ko": 0, "ok": 69, "total": 69 }, "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": 127, "total": 127 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 127, "total": 127 }, "minResponseTime": { "ko": 0, "ok": 127, "total": 127 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 127, "total": 127 }, "percentiles2": { "ko": 0, "ok": 127, "total": 127 }, "percentiles3": { "ko": 0, "ok": 127, "total": 127 }, "percentiles4": { "ko": 0, "ok": 127, "total": 127 }, "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": 90, "total": 90 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 90, "total": 90 }, "minResponseTime": { "ko": 0, "ok": 90, "total": 90 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 90, "total": 90 }, "percentiles2": { "ko": 0, "ok": 90, "total": 90 }, "percentiles3": { "ko": 0, "ok": 90, "total": 90 }, "percentiles4": { "ko": 0, "ok": 90, "total": 90 }, "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": 68, "total": 68 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0016666666666666668, "total": 0.0016666666666666668 }, "meanResponseTime": { "ko": 0, "ok": 68, "total": 68 }, "minResponseTime": { "ko": 0, "ok": 68, "total": 68 }, "name": "User Authorize PKCE stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 68, "total": 68 }, "percentiles2": { "ko": 0, "ok": 68, "total": 68 }, "percentiles3": { "ko": 0, "ok": 68, "total": 68 }, "percentiles4": { "ko": 0, "ok": 68, "total": 68 }, "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": 29010, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.9689858368655 }, "group2": { "count": 5, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.017230090630276713 }, "group3": { "count": 3, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.010338054378166029 }, "group4": { "count": 1, "htmlName": "failed", "name": "failed", "percentage": 0.0034460181260553436 }, "maxResponseTime": { "ko": 205, "ok": 7372, "total": 7372 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0016666666666666668, "ok": 48.36333333333334, "total": 48.365 }, "meanResponseTime": { "ko": 205, "ok": 48, "total": 48 }, "minResponseTime": { "ko": 205, "ok": 40, "total": 40 }, "name": "queryPartial", "numberOfRequests": { "ko": 1, "ok": 29018, "total": 29019 }, "percentiles1": { "ko": 205, "ok": 47, "total": 47 }, "percentiles2": { "ko": 205, "ok": 49, "total": 49 }, "percentiles3": { "ko": 205, "ok": 52, "total": 52 }, "percentiles4": { "ko": 205, "ok": 57, "total": 57 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 50, "total": 50 } } ], "global": { "group1": { "count": 29015, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.96899117971334 }, "group2": { "count": 5, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.017227122381477396 }, "group3": { "count": 3, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.010336273428886439 }, "group4": { "count": 1, "htmlName": "failed", "name": "failed", "percentage": 0.0034454244762954795 }, "maxResponseTime": { "ko": 205, "ok": 7372, "total": 7372 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0016666666666666668, "ok": 48.37166666666667, "total": 48.373333333333335 }, "meanResponseTime": { "ko": 205, "ok": 48, "total": 48 }, "minResponseTime": { "ko": 205, "ok": 40, "total": 40 }, "name": "All Requests", "numberOfRequests": { "ko": 1, "ok": 29023, "total": 29024 }, "percentiles1": { "ko": 205, "ok": 47, "total": 47 }, "percentiles2": { "ko": 205, "ok": 49, "total": 49 }, "percentiles3": { "ko": 205, "ok": 52, "total": 52 }, "percentiles4": { "ko": 205, "ok": 58, "total": 58 }, "standardDeviation": { "ko": 0, "ok": 50, "total": 50 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 19:59:11] Reporting-query-7 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 19:59:11] Reporting-query-7 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/12_Reporting-query-7/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 19:59:11] Reporting-query-7 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped