--Task-- name: Reporting-update-500 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-update-500', 'Logs-parse-update-1000', 'Logs-parse-update-1500', 'Logs-parse-update-1600', 'Logs-parse-update-1700', 'Logs-parse-patch-500', 'Logs-parse-patch-1000', 'Logs-parse-patch-1500', 'Logs-parse-patch-1700', 'Logs-parse-patch-1800'] preceding_task: None options: {'task_name': 'update-500'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 15:51:09 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 15:51:09 - 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-update-500/stdout.txt Pod stderr file : /results/orders/Reporting-update-500/stderr.txt Pod log : /results/orders/Reporting-update-500 ________________________________________________________________________________ [2025-03-28 15:51:09] Reporting-update-500 step1 : Write order for overseer to generate the report ________________________________________________________________________________ 2025-03-28 15:51:09 - INFO: Get overseer pod resources requests memory 2025-03-28 15:51:09,153 INFO 2025-03-28 15:51:09,153 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-59c948f569-rgrkv --output jsonpath={.spec.containers[].resources.requests.memory} 2025-03-28 15:51:09,153 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 15:51:09,428 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:51:09,428 DEBUG --- stdout --- 2025-03-28 15:51:09,428 DEBUG 8Gi 2025-03-28 15:51:09,428 DEBUG --- stderr --- 2025-03-28 15:51:09,428 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-update-500/order.json { "name": "Reporting-update-500", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/update-500\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"update-500.tar.gz\"", "out-dir": "/results/orders/Reporting-update-500" } ________________________________________________________________________________ [2025-03-28 15:51:09] Reporting-update-500 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 15:51:09,429 INFO 2025-03-28 15:51:09,429 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 15:51:09,429 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 15:51:09,687 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 15:51:09,687 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:51:09,687 DEBUG --- stdout --- 2025-03-28 15:51:09,687 DEBUG WAITING 2025-03-28 15:51:09,687 DEBUG --- stderr --- 2025-03-28 15:51:09,687 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 28 0 --:--:-- --:--:-- --:--:-- 28 ________________________________________________________________________________ [2025-03-28 15:51:09] Reporting-update-500 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 15:51:09,687 INFO 2025-03-28 15:51:09,687 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 15:51:09,687 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 15:51:09,964 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 15:51:09,964 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:51:09,964 DEBUG --- stdout --- 2025-03-28 15:51:09,964 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 15:51:09,964 DEBUG --- stderr --- 2025-03-28 15:51:09,964 DEBUG 2025-03-28 15:51:09,965 INFO 2025-03-28 15:51:09,965 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-update-500/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 15:51:09,965 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 15:51:11,428 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:51:11,428 DEBUG --- stdout --- 2025-03-28 15:51:11,428 DEBUG 2025-03-28 15:51:11,428 DEBUG --- stderr --- 2025-03-28 15:51:11,428 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 15:51:11] Reporting-update-500 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 15:51:16,429 INFO 2025-03-28 15:51:16,429 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 15:51:16,429 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 15:51:16,645 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:51:21,859 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:51:27,074 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:51:32,289 INFO [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:51:37,504 INFO [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:51:42,721 INFO [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:51:47,937 INFO [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:51:53,221 INFO [loop_until]: Function succeeded after 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:51:58,435 INFO [loop_until]: Function succeeded after 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:52:03,650 INFO [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:52:08,864 INFO [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:52:14,083 INFO [loop_until]: Function succeeded after 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:52:19,298 INFO [loop_until]: Function succeeded after 1m 02s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:52:24,515 INFO [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:52:29,732 INFO [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:52:34,990 INFO [loop_until]: Function succeeded after 1m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:52:40,276 INFO [loop_until]: Function succeeded after 1m 23s (rc=0) - expected pattern found 2025-03-28 15:52:40,276 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:40,276 DEBUG --- stdout --- 2025-03-28 15:52:40,276 DEBUG WAITING 2025-03-28 15:52:40,276 DEBUG --- stderr --- 2025-03-28 15:52:40,276 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 25 0 --:--:-- --:--:-- --:--:-- 25 ________________________________________________________________________________ [2025-03-28 15:52:40] Reporting-update-500 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 15:52:40,276 INFO 2025-03-28 15:52:40,276 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 15:52:40,277 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 15:52:40,560 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 15:52:40,560 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:40,560 DEBUG --- stdout --- 2025-03-28 15:52:40,560 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 15:52:40,560 DEBUG --- stderr --- 2025-03-28 15:52:40,560 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 15:52:40,561 INFO 2025-03-28 15:52:40,561 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/update-500/update-500.tar.gz" ]; then echo "/results/gatling/update-500/update-500.tar.gz file found"; exit 0; else echo "/results/gatling/update-500/update-500.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/update-500/update-500.tar.gz file found" 2025-03-28 15:52:40,561 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 15:52:41,368 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 15:52:41,369 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:41,369 DEBUG --- stdout --- 2025-03-28 15:52:41,369 DEBUG /results/gatling/update-500/update-500.tar.gz file found 2025-03-28 15:52:41,369 DEBUG --- stderr --- 2025-03-28 15:52:41,369 DEBUG 2025-03-28 15:52:41,369 INFO 2025-03-28 15:52:41,369 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/gatling/update-500/update-500.tar.gz 2025-03-28 15:52:42,175 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 15:52:42,175 DEBUG --- stdout --- 2025-03-28 15:52:42,175 DEBUG 296 /results/gatling/update-500/update-500.tar.gz 2025-03-28 15:52:42,175 DEBUG --- stderr --- 2025-03-28 15:52:42,175 DEBUG 2025-03-28 15:52:42,175 INFO - Download file (size 296k) source : /results/gatling/update-500/update-500.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-500.tar.gz 2025-03-28 15:52:42,176 INFO 2025-03-28 15:52:42,176 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/update-500/update-500.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-500.tar.gz 2025-03-28 15:52:42,176 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 15:52:43,178 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:43,178 DEBUG --- stdout --- 2025-03-28 15:52:43,178 DEBUG tar: Removing leading `/' from member names 2025-03-28 15:52:43,178 DEBUG --- stderr --- 2025-03-28 15:52:43,178 DEBUG ________________________________________________________________________________ [2025-03-28 15:52:43] Reporting-update-500 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 15:52:43,178 INFO 2025-03-28 15:52:43,179 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-500.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/01_update-500/gatling 2025-03-28 15:52:43,193 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 15:52:43,193 DEBUG --- stdout --- 2025-03-28 15:52:43,193 DEBUG 2025-03-28 15:52:43,194 DEBUG --- stderr --- 2025-03-28 15:52:43,194 DEBUG 2025-03-28 15:52:43,194 INFO ________________________________________________________________________________ [2025-03-28 15:52:43] Reporting-update-500 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 15:52:43,194 INFO 2025-03-28 15:52:43,194 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 15:52:43,194 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 15:52:43,474 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 15:52:43,474 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:43,474 DEBUG --- stdout --- 2025-03-28 15:52:43,474 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 15:52:43,474 DEBUG --- stderr --- 2025-03-28 15:52:43,474 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 15:52:43,476 INFO 2025-03-28 15:52:43,476 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-update-500/rc.txt" ]; then echo "/results/orders/Reporting-update-500/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-update-500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-500/rc.txt file found" 2025-03-28 15:52:43,476 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 15:52:44,295 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 15:52:44,295 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:44,295 DEBUG --- stdout --- 2025-03-28 15:52:44,295 DEBUG /results/orders/Reporting-update-500/rc.txt file found 2025-03-28 15:52:44,295 DEBUG --- stderr --- 2025-03-28 15:52:44,295 DEBUG 2025-03-28 15:52:44,295 INFO 2025-03-28 15:52:44,295 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-update-500/rc.txt 2025-03-28 15:52:45,101 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 15:52:45,101 DEBUG --- stdout --- 2025-03-28 15:52:45,101 DEBUG 4 /results/orders/Reporting-update-500/rc.txt 2025-03-28 15:52:45,101 DEBUG --- stderr --- 2025-03-28 15:52:45,101 DEBUG 2025-03-28 15:52:45,101 INFO - Download file (size 4k) source : /results/orders/Reporting-update-500/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-update-500/rc.txt 2025-03-28 15:52:45,102 INFO 2025-03-28 15:52:45,102 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-update-500/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-update-500/rc.txt 2025-03-28 15:52:45,102 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 15:52:45,864 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:45,864 DEBUG --- stdout --- 2025-03-28 15:52:45,864 DEBUG tar: Removing leading `/' from member names 2025-03-28 15:52:45,864 DEBUG --- stderr --- 2025-03-28 15:52:45,864 DEBUG 2025-03-28 15:52:45,864 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-update-500/rc.txt is small (1 lines), printing all lines: 2025-03-28 15:52:45,864 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 15:52:45] Reporting-update-500 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 15:52:46] Reporting-update-500 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 15:52:46,102 INFO 2025-03-28 15:52:46,102 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 15:52:46,102 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 15:52:46,379 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 15:52:46,379 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:46,379 DEBUG --- stdout --- 2025-03-28 15:52:46,379 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 15:52:46,379 DEBUG --- stderr --- 2025-03-28 15:52:46,379 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 15:52:46,380 INFO 2025-03-28 15:52:46,380 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-update-500/stdout.txt" ]; then echo "/results/orders/Reporting-update-500/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-update-500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-500/stdout.txt file found" 2025-03-28 15:52:46,380 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 15:52:47,189 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 15:52:47,189 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:47,189 DEBUG --- stdout --- 2025-03-28 15:52:47,190 DEBUG /results/orders/Reporting-update-500/stdout.txt file found 2025-03-28 15:52:47,190 DEBUG --- stderr --- 2025-03-28 15:52:47,190 DEBUG 2025-03-28 15:52:47,190 INFO 2025-03-28 15:52:47,190 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-update-500/stdout.txt 2025-03-28 15:52:47,988 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 15:52:47,988 DEBUG --- stdout --- 2025-03-28 15:52:47,988 DEBUG 8 /results/orders/Reporting-update-500/stdout.txt 2025-03-28 15:52:47,988 DEBUG --- stderr --- 2025-03-28 15:52:47,988 DEBUG 2025-03-28 15:52:47,988 INFO - Download file (size 8k) source : /results/orders/Reporting-update-500/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-update-500/stdout.txt 2025-03-28 15:52:47,989 INFO 2025-03-28 15:52:47,989 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-update-500/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-update-500/stdout.txt 2025-03-28 15:52:47,989 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 15:52:48,755 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:48,755 DEBUG --- stdout --- 2025-03-28 15:52:48,755 DEBUG tar: Removing leading `/' from member names 2025-03-28 15:52:48,755 DEBUG --- stderr --- 2025-03-28 15:52:48,755 DEBUG 2025-03-28 15:52:48,755 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-update-500/stdout.txt is small (95 lines), printing all lines: 2025-03-28 15:52:48,755 INFO ----- output ----- 2025-03-28 15:51:12,252 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpbkyghtiv 2025-03-28 15:51:12,253 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/update-500/loadtest-20250328054301335" -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/tmpbkyghtiv/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/tmpbkyghtiv/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 --- 15:51:14,082 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 15:51:14,084 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 15:51:14,084 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 15:51:14,084 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 15:51:14,090 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 15:51:14,090 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 15:51:14,097 |-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 15:51:14,097 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 15:51:14,098 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 15:51:14,098 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 15:51:14,101 |-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] 15:51:14,105 |-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 15:51:14,149 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 15:51:14,149 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 15:51:14,189 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 15:51:14,189 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 15:51:14,195 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 15:51:14,216 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 15:51:14,216 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 15:51:14,216 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 15:51:14,217 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 15:51:14,217 |-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 79s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 1790802 (OK=1790784 KO=18 ) > min response time 37 (OK=61 KO=37 ) > max response time 60000 (OK=26882 KO=60000 ) > mean response time 83 (OK=83 KO=6718 ) > std deviation 55 (OK=26 KO=13721 ) > response time 50th percentile 79 (OK=79 KO=124 ) > response time 75th percentile 92 (OK=92 KO=10001 ) > response time 95th percentile 99 (OK=99 KO=17524 ) > response time 99th percentile 127 (OK=127 KO=51505 ) > mean requests/sec 497.44 (OK=497.44 KO=0.01 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 1790735 ( 100%) > 800 ms <= t < 1200 ms 34 ( 0%) > t >= 1200 ms 15 ( 0%) > failed 18 ( 0%) ---- Errors -------------------------------------------------------------------- > status.find.in(200), but actually found 502 9 (50.00%) > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 4 (22.22%) r 10000ms > status.find.in(200), but actually found 503 2 (11.11%) > j.i.IOException: Premature close 2 (11.11%) > Request timeout to openam-perf-200m.forgeblocks.com/34.107.203 1 ( 5.56%) .37:443 after 60000 ms ================================================================================ Reports generated, please open the following file: file:///results/gatling/update-500/loadtest-20250328054301335/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 01:21 min [INFO] Finished at: 2025-03-28T15:52:34Z [INFO] ------------------------------------------------------------------------ 2025-03-28 15:52:34,754 INFO Gatling simulation run ended successfully 2025-03-28 15:52:34,755 INFO Creating archive update-500.tar.gz with report files 2025-03-28 15:52:34,850 INFO Archive /results/gatling/update-500/update-500.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 15:52:48] Reporting-update-500 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 15:52:48] Reporting-update-500 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 15:52:48,756 INFO 2025-03-28 15:52:48,756 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 15:52:48,756 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 15:52:49,032 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 15:52:49,033 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:49,033 DEBUG --- stdout --- 2025-03-28 15:52:49,033 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 15:52:49,033 DEBUG --- stderr --- 2025-03-28 15:52:49,033 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 15:52:49,034 INFO 2025-03-28 15:52:49,034 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-update-500/stderr.txt" ]; then echo "/results/orders/Reporting-update-500/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-update-500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-500/stderr.txt file found" 2025-03-28 15:52:49,034 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 15:52:49,830 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 15:52:49,830 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:49,830 DEBUG --- stdout --- 2025-03-28 15:52:49,830 DEBUG /results/orders/Reporting-update-500/stderr.txt file found 2025-03-28 15:52:49,830 DEBUG --- stderr --- 2025-03-28 15:52:49,830 DEBUG 2025-03-28 15:52:49,830 INFO 2025-03-28 15:52:49,831 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-update-500/stderr.txt 2025-03-28 15:52:50,634 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 15:52:50,634 DEBUG --- stdout --- 2025-03-28 15:52:50,634 DEBUG 0 /results/orders/Reporting-update-500/stderr.txt 2025-03-28 15:52:50,634 DEBUG --- stderr --- 2025-03-28 15:52:50,634 DEBUG 2025-03-28 15:52:50,634 INFO - Download file (size 0k) source : /results/orders/Reporting-update-500/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-update-500/stderr.txt 2025-03-28 15:52:50,635 INFO 2025-03-28 15:52:50,635 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-update-500/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-update-500/stderr.txt 2025-03-28 15:52:50,635 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 15:52:51,367 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:52:51,367 DEBUG --- stdout --- 2025-03-28 15:52:51,367 DEBUG tar: Removing leading `/' from member names 2025-03-28 15:52:51,367 DEBUG --- stderr --- 2025-03-28 15:52:51,367 DEBUG 2025-03-28 15:52:51,367 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/12_Reporting-update-500/stderr.txt is small (0 lines), printing all lines: 2025-03-28 15:52:51,367 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 15:52:51] Reporting-update-500 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 15:52:51] Reporting-update-500 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 UpdateManagedUser [warn] responseTimePercentiles was not found in /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/01_update-500/gatling/req_updatemanagedus--1469575327.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 15:52:51] Reporting-update-500 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 1790802, "ok": 1790784, "ko": 18 }, "minResponseTime": { "total": 37, "ok": 61, "ko": 37 }, "maxResponseTime": { "total": 60000, "ok": 26882, "ko": 60000 }, "meanResponseTime": { "total": 83, "ok": 83, "ko": 6718 }, "standardDeviation": { "total": 55, "ok": 26, "ko": 13721 }, "percentiles1": { "total": 79, "ok": 79, "ko": 124 }, "percentiles2": { "total": 92, "ok": 92, "ko": 10001 }, "percentiles3": { "total": 99, "ok": 99, "ko": 17524 }, "percentiles4": { "total": 127, "ok": 127, "ko": 51505 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1790735, "percentage": 99.99625865952797 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 34, "percentage": 0.001898590687300997 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 15, "percentage": 0.0008376135385151459 }, "group4": { "name": "failed", "htmlName": "failed", "count": 18, "percentage": 0.0010051362462181748 }, "meanNumberOfRequestsPerSecond": { "total": 497.445, "ok": 497.44, "ko": 0.005 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 61, "ok": 61, "ko": 0 }, "maxResponseTime": { "total": 68, "ok": 68, "ko": 0 }, "meanResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "standardDeviation": { "total": 4, "ok": 4, "ko": 0 }, "percentiles1": { "total": 65, "ok": 65, "ko": 0 }, "percentiles2": { "total": 66, "ok": 66, "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": 2, "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.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 110, "ok": 110, "ko": 0 }, "maxResponseTime": { "total": 113, "ok": 113, "ko": 0 }, "meanResponseTime": { "total": 112, "ok": 112, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 112, "ok": 112, "ko": 0 }, "percentiles2": { "total": 112, "ok": 112, "ko": 0 }, "percentiles3": { "total": 113, "ok": 113, "ko": 0 }, "percentiles4": { "total": 113, "ok": 113, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "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.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "Skip 2FA", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 67, "ok": 67, "ko": 0 }, "maxResponseTime": { "total": 69, "ok": 69, "ko": 0 }, "meanResponseTime": { "total": 68, "ok": 68, "ko": 0 }, "standardDeviation": { "total": 1, "ok": 1, "ko": 0 }, "percentiles1": { "total": 68, "ok": 68, "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": 2, "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.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "User Authorize PKCE stage", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "maxResponseTime": { "total": 67, "ok": 67, "ko": 0 }, "meanResponseTime": { "total": 66, "ok": 66, "ko": 0 }, "standardDeviation": { "total": 1, "ok": 1, "ko": 0 }, "percentiles1": { "total": 66, "ok": 66, "ko": 0 }, "percentiles2": { "total": 67, "ok": 67, "ko": 0 }, "percentiles3": { "total": 67, "ok": 67, "ko": 0 }, "percentiles4": { "total": 67, "ok": 67, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "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.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "User AccessToken PKCE stage", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "maxResponseTime": { "total": 69, "ok": 69, "ko": 0 }, "meanResponseTime": { "total": 66, "ok": 66, "ko": 0 }, "standardDeviation": { "total": 3, "ok": 3, "ko": 0 }, "percentiles1": { "total": 66, "ok": 66, "ko": 0 }, "percentiles2": { "total": 68, "ok": 68, "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": 2, "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.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "UpdateManagedUser", "numberOfRequests": { "total": 1790792, "ok": 1790774, "ko": 18 }, "minResponseTime": { "total": 37, "ok": 64, "ko": 37 }, "maxResponseTime": { "total": 60000, "ok": 26882, "ko": 60000 }, "meanResponseTime": { "total": 83, "ok": 83, "ko": 6718 }, "standardDeviation": { "total": 55, "ok": 26, "ko": 13721 }, "percentiles1": { "total": 79, "ok": 79, "ko": 124 }, "percentiles2": { "total": 92, "ok": 92, "ko": 10001 }, "percentiles3": { "total": 99, "ok": 99, "ko": 17524 }, "percentiles4": { "total": 127, "ok": 127, "ko": 51505 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1790725, "percentage": 99.99625863863587 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 34, "percentage": 0.001898601289261958 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 15, "percentage": 0.0008376182158508637 }, "group4": { "name": "failed", "htmlName": "failed", "count": 18, "percentage": 0.0010051418590210366 }, "meanNumberOfRequestsPerSecond": { "total": 497.4422222222222, "ok": 497.4372222222222, "ko": 0.005 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 497, "avg_response_time": 83, "max_response_time": 26882, "min_response_time": 61, "num_requests": 1790802, "num_requests_pass": 1790784, "num_requests_percent_pass": 99.999 }, "options": "; testMode:open; testCase:idm.ManagedUsersUpdate; duration:3600; throughput:500", "stats": { "details": [ { "group1": { "count": 2, "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.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 65, "total": 65 }, "minResponseTime": { "ko": 0, "ok": 61, "total": 61 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 65, "total": 65 }, "percentiles2": { "ko": 0, "ok": 66, "total": 66 }, "percentiles3": { "ko": 0, "ok": 68, "total": 68 }, "percentiles4": { "ko": 0, "ok": 68, "total": 68 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 4, "total": 4 } }, { "group1": { "count": 2, "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": 113, "total": 113 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 112, "total": 112 }, "minResponseTime": { "ko": 0, "ok": 110, "total": 110 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 112, "total": 112 }, "percentiles2": { "ko": 0, "ok": 112, "total": 112 }, "percentiles3": { "ko": 0, "ok": 113, "total": 113 }, "percentiles4": { "ko": 0, "ok": 113, "total": 113 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } }, { "group1": { "count": 2, "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.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 68, "total": 68 }, "minResponseTime": { "ko": 0, "ok": 67, "total": 67 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 68, "total": 68 }, "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": 1, "total": 1 } }, { "group1": { "count": 2, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 67, "total": 67 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 66, "total": 66 }, "minResponseTime": { "ko": 0, "ok": 65, "total": 65 }, "name": "User Authorize PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 66, "total": 66 }, "percentiles2": { "ko": 0, "ok": 67, "total": 67 }, "percentiles3": { "ko": 0, "ok": 67, "total": 67 }, "percentiles4": { "ko": 0, "ok": 67, "total": 67 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 1, "total": 1 } }, { "group1": { "count": 2, "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.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 66, "total": 66 }, "minResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 66, "total": 66 }, "percentiles2": { "ko": 0, "ok": 68, "total": 68 }, "percentiles3": { "ko": 0, "ok": 69, "total": 69 }, "percentiles4": { "ko": 0, "ok": 69, "total": 69 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 3, "total": 3 } }, { "group1": { "count": 1790725, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.99625863863587 }, "group2": { "count": 34, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.001898601289261958 }, "group3": { "count": 15, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0008376182158508637 }, "group4": { "count": 18, "htmlName": "failed", "name": "failed", "percentage": 0.0010051418590210366 }, "maxResponseTime": { "ko": 60000, "ok": 26882, "total": 60000 }, "meanNumberOfRequestsPerSecond": { "ko": 0.005, "ok": 497.4372222222222, "total": 497.4422222222222 }, "meanResponseTime": { "ko": 6718, "ok": 83, "total": 83 }, "minResponseTime": { "ko": 37, "ok": 64, "total": 37 }, "name": "UpdateManagedUser", "numberOfRequests": { "ko": 18, "ok": 1790774, "total": 1790792 }, "percentiles1": { "ko": 124, "ok": 79, "total": 79 }, "percentiles2": { "ko": 10001, "ok": 92, "total": 92 }, "percentiles3": { "ko": 17524, "ok": 99, "total": 99 }, "percentiles4": { "ko": 51505, "ok": 127, "total": 127 }, "spikes": [], "standardDeviation": { "ko": 13721, "ok": 26, "total": 55 } } ], "global": { "group1": { "count": 1790735, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.99625865952797 }, "group2": { "count": 34, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.001898590687300997 }, "group3": { "count": 15, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0008376135385151459 }, "group4": { "count": 18, "htmlName": "failed", "name": "failed", "percentage": 0.0010051362462181748 }, "maxResponseTime": { "ko": 60000, "ok": 26882, "total": 60000 }, "meanNumberOfRequestsPerSecond": { "ko": 0.005, "ok": 497.44, "total": 497.445 }, "meanResponseTime": { "ko": 6718, "ok": 83, "total": 83 }, "minResponseTime": { "ko": 37, "ok": 61, "total": 37 }, "name": "All Requests", "numberOfRequests": { "ko": 18, "ok": 1790784, "total": 1790802 }, "percentiles1": { "ko": 124, "ok": 79, "total": 79 }, "percentiles2": { "ko": 10001, "ok": 92, "total": 92 }, "percentiles3": { "ko": 17524, "ok": 99, "total": 99 }, "percentiles4": { "ko": 51505, "ok": 127, "total": 127 }, "standardDeviation": { "ko": 13721, "ok": 26, "total": 55 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 15:52:51] Reporting-update-500 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 15:52:51] Reporting-update-500 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-update-500/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 15:52:51] Reporting-update-500 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped