--Task-- name: Reporting-patch-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', 'Reporting-update-500', 'Reporting-update-1000', 'Reporting-update-1500', 'Reporting-update-1600', 'Reporting-update-1700'] preceding_task: None options: {'task_name': 'patch-500'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 16:09:03 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 16:09:03 - 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-patch-500/stdout.txt Pod stderr file : /results/orders/Reporting-patch-500/stderr.txt Pod log : /results/orders/Reporting-patch-500 ________________________________________________________________________________ [2025-03-28 16:09:03] Reporting-patch-500 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/17_Reporting-patch-500/order.json { "name": "Reporting-patch-500", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/patch-500\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"patch-500.tar.gz\"", "out-dir": "/results/orders/Reporting-patch-500" } ________________________________________________________________________________ [2025-03-28 16:09:03] Reporting-patch-500 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 16:09:03,361 INFO 2025-03-28 16:09:03,361 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:09:03,361 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 16:09:03,578 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:09:03,578 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:09:03,578 DEBUG --- stdout --- 2025-03-28 16:09:03,578 DEBUG WAITING 2025-03-28 16:09:03,578 DEBUG --- stderr --- 2025-03-28 16:09:03,578 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 16:09:03] Reporting-patch-500 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 16:09:03,579 INFO 2025-03-28 16:09:03,579 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 16:09:03,579 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:09:03,857 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:09:03,857 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:09:03,857 DEBUG --- stdout --- 2025-03-28 16:09:03,857 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:09:03,857 DEBUG --- stderr --- 2025-03-28 16:09:03,857 DEBUG 2025-03-28 16:09:03,857 INFO 2025-03-28 16:09:03,857 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/17_Reporting-patch-500/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 16:09:03,857 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:09:05,221 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:09:05,221 DEBUG --- stdout --- 2025-03-28 16:09:05,221 DEBUG 2025-03-28 16:09:05,221 DEBUG --- stderr --- 2025-03-28 16:09:05,221 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 16:09:05] Reporting-patch-500 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 16:09:10,222 INFO 2025-03-28 16:09:10,222 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:09:10,222 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 16:09:10,437 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:09:15,696 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:09:20,910 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:09:26,126 INFO [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:09:31,344 INFO [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:09:36,558 INFO [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:09:41,773 INFO [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:09:47,032 INFO [loop_until]: Function succeeded after 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:09:52,246 INFO [loop_until]: Function succeeded after 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:09:57,461 INFO [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:10:02,675 INFO [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:10:07,890 INFO [loop_until]: Function succeeded after 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:10:13,105 INFO [loop_until]: Function succeeded after 1m 02s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:10:18,322 INFO [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:10:23,537 INFO [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:10:28,753 INFO [loop_until]: Function succeeded after 1m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:10:33,967 INFO [loop_until]: Function succeeded after 1m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:10:39,183 INFO [loop_until]: Function succeeded after 1m 28s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:10:44,398 INFO [loop_until]: Function succeeded after 1m 34s (rc=0) - expected pattern found 2025-03-28 16:10:44,398 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:44,398 DEBUG --- stdout --- 2025-03-28 16:10:44,398 DEBUG WAITING 2025-03-28 16:10:44,398 DEBUG --- stderr --- 2025-03-28 16:10:44,398 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 16:10:44] Reporting-patch-500 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 16:10:44,399 INFO 2025-03-28 16:10:44,399 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 16:10:44,399 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:10:44,705 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:10:44,705 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:44,705 DEBUG --- stdout --- 2025-03-28 16:10:44,705 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:10:44,705 DEBUG --- stderr --- 2025-03-28 16:10:44,705 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:10:44,706 INFO 2025-03-28 16:10:44,706 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/patch-500/patch-500.tar.gz" ]; then echo "/results/gatling/patch-500/patch-500.tar.gz file found"; exit 0; else echo "/results/gatling/patch-500/patch-500.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/patch-500/patch-500.tar.gz file found" 2025-03-28 16:10:44,706 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:10:45,576 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:10:45,576 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:45,576 DEBUG --- stdout --- 2025-03-28 16:10:45,577 DEBUG /results/gatling/patch-500/patch-500.tar.gz file found 2025-03-28 16:10:45,577 DEBUG --- stderr --- 2025-03-28 16:10:45,577 DEBUG 2025-03-28 16:10:45,577 INFO 2025-03-28 16:10:45,577 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/gatling/patch-500/patch-500.tar.gz 2025-03-28 16:10:46,378 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:10:46,378 DEBUG --- stdout --- 2025-03-28 16:10:46,378 DEBUG 296 /results/gatling/patch-500/patch-500.tar.gz 2025-03-28 16:10:46,378 DEBUG --- stderr --- 2025-03-28 16:10:46,378 DEBUG 2025-03-28 16:10:46,378 INFO - Download file (size 296k) source : /results/gatling/patch-500/patch-500.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-500.tar.gz 2025-03-28 16:10:46,379 INFO 2025-03-28 16:10:46,379 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/patch-500/patch-500.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-500.tar.gz 2025-03-28 16:10:46,379 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:10:47,382 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:47,383 DEBUG --- stdout --- 2025-03-28 16:10:47,383 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:10:47,383 DEBUG --- stderr --- 2025-03-28 16:10:47,383 DEBUG ________________________________________________________________________________ [2025-03-28 16:10:47] Reporting-patch-500 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 16:10:47,383 INFO 2025-03-28 16:10:47,383 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-500.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/06_patch-500/gatling 2025-03-28 16:10:47,397 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:10:47,398 DEBUG --- stdout --- 2025-03-28 16:10:47,398 DEBUG 2025-03-28 16:10:47,398 DEBUG --- stderr --- 2025-03-28 16:10:47,398 DEBUG 2025-03-28 16:10:47,398 INFO ________________________________________________________________________________ [2025-03-28 16:10:47] Reporting-patch-500 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 16:10:47,398 INFO 2025-03-28 16:10:47,398 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 16:10:47,398 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:10:47,677 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:10:47,677 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:47,677 DEBUG --- stdout --- 2025-03-28 16:10:47,677 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:10:47,677 DEBUG --- stderr --- 2025-03-28 16:10:47,677 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:10:47,678 INFO 2025-03-28 16:10:47,678 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-patch-500/rc.txt" ]; then echo "/results/orders/Reporting-patch-500/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-500/rc.txt file found" 2025-03-28 16:10:47,678 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:10:48,477 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:10:48,477 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:48,477 DEBUG --- stdout --- 2025-03-28 16:10:48,477 DEBUG /results/orders/Reporting-patch-500/rc.txt file found 2025-03-28 16:10:48,477 DEBUG --- stderr --- 2025-03-28 16:10:48,477 DEBUG 2025-03-28 16:10:48,477 INFO 2025-03-28 16:10:48,478 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-patch-500/rc.txt 2025-03-28 16:10:49,284 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:10:49,284 DEBUG --- stdout --- 2025-03-28 16:10:49,284 DEBUG 4 /results/orders/Reporting-patch-500/rc.txt 2025-03-28 16:10:49,284 DEBUG --- stderr --- 2025-03-28 16:10:49,284 DEBUG 2025-03-28 16:10:49,284 INFO - Download file (size 4k) source : /results/orders/Reporting-patch-500/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/17_Reporting-patch-500/rc.txt 2025-03-28 16:10:49,284 INFO 2025-03-28 16:10:49,285 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-patch-500/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/17_Reporting-patch-500/rc.txt 2025-03-28 16:10:49,285 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:10:50,013 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:50,013 DEBUG --- stdout --- 2025-03-28 16:10:50,013 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:10:50,013 DEBUG --- stderr --- 2025-03-28 16:10:50,013 DEBUG 2025-03-28 16:10:50,013 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/17_Reporting-patch-500/rc.txt is small (1 lines), printing all lines: 2025-03-28 16:10:50,013 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 16:10:50] Reporting-patch-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 16:10:50] Reporting-patch-500 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 16:10:50,320 INFO 2025-03-28 16:10:50,320 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 16:10:50,320 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:10:50,601 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:10:50,601 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:50,601 DEBUG --- stdout --- 2025-03-28 16:10:50,601 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:10:50,601 DEBUG --- stderr --- 2025-03-28 16:10:50,601 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:10:50,602 INFO 2025-03-28 16:10:50,602 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-patch-500/stdout.txt" ]; then echo "/results/orders/Reporting-patch-500/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-500/stdout.txt file found" 2025-03-28 16:10:50,602 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:10:51,411 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:10:51,411 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:51,411 DEBUG --- stdout --- 2025-03-28 16:10:51,411 DEBUG /results/orders/Reporting-patch-500/stdout.txt file found 2025-03-28 16:10:51,411 DEBUG --- stderr --- 2025-03-28 16:10:51,411 DEBUG 2025-03-28 16:10:51,412 INFO 2025-03-28 16:10:51,412 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-patch-500/stdout.txt 2025-03-28 16:10:52,217 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:10:52,218 DEBUG --- stdout --- 2025-03-28 16:10:52,218 DEBUG 8 /results/orders/Reporting-patch-500/stdout.txt 2025-03-28 16:10:52,218 DEBUG --- stderr --- 2025-03-28 16:10:52,218 DEBUG 2025-03-28 16:10:52,218 INFO - Download file (size 8k) source : /results/orders/Reporting-patch-500/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/17_Reporting-patch-500/stdout.txt 2025-03-28 16:10:52,218 INFO 2025-03-28 16:10:52,218 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-patch-500/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/17_Reporting-patch-500/stdout.txt 2025-03-28 16:10:52,218 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:10:52,945 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:52,945 DEBUG --- stdout --- 2025-03-28 16:10:52,945 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:10:52,945 DEBUG --- stderr --- 2025-03-28 16:10:52,945 DEBUG 2025-03-28 16:10:52,945 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/17_Reporting-patch-500/stdout.txt is small (89 lines), printing all lines: 2025-03-28 16:10:52,945 INFO ----- output ----- 2025-03-28 16:09:05,842 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp_0q4btg6 2025-03-28 16:09:05,843 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/patch-500/loadtest-20250328104632531" -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/tmp_0q4btg6/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/tmp_0q4btg6/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 --- 16:09:07,675 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 16:09:07,676 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 16:09:07,676 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:09:07,677 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 16:09:07,682 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 16:09:07,682 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 16:09:07,689 |-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 16:09:07,689 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 16:09:07,690 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 16:09:07,690 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 16:09:07,693 |-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] 16:09:07,697 |-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 16:09:07,741 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 16:09:07,741 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 16:09:07,780 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 16:09:07,780 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 16:09:07,786 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 16:09:07,807 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 16:09:07,807 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 16:09:07,808 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 16:09:07,808 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 16:09:07,808 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 118 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY Parsing log file(s)... Parsing log file(s) done in 90s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 1794232 (OK=1794226 KO=6 ) > min response time 37 (OK=54 KO=37 ) > max response time 1558 (OK=1558 KO=41 ) > mean response time 79 (OK=79 KO=40 ) > std deviation 14 (OK=14 KO=2 ) > response time 50th percentile 75 (OK=75 KO=40 ) > response time 75th percentile 88 (OK=88 KO=41 ) > response time 95th percentile 94 (OK=94 KO=41 ) > response time 99th percentile 117 (OK=117 KO=41 ) > mean requests/sec 498.4 (OK=498.4 KO=0 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 1794180 ( 100%) > 800 ms <= t < 1200 ms 45 ( 0%) > t >= 1200 ms 1 ( 0%) > failed 6 ( 0%) ---- Errors -------------------------------------------------------------------- > status.find.in(200), but actually found 502 6 (100.0%) ================================================================================ Reports generated, please open the following file: file:///results/gatling/patch-500/loadtest-20250328104632531/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 01:32 min [INFO] Finished at: 2025-03-28T16:10:39Z [INFO] ------------------------------------------------------------------------ 2025-03-28 16:10:39,539 INFO Gatling simulation run ended successfully 2025-03-28 16:10:39,539 INFO Creating archive patch-500.tar.gz with report files 2025-03-28 16:10:39,635 INFO Archive /results/gatling/patch-500/patch-500.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 16:10:52] Reporting-patch-500 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 16:10:52] Reporting-patch-500 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 16:10:52,946 INFO 2025-03-28 16:10:52,946 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 16:10:52,946 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:10:53,225 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:10:53,225 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:53,225 DEBUG --- stdout --- 2025-03-28 16:10:53,225 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:10:53,225 DEBUG --- stderr --- 2025-03-28 16:10:53,225 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:10:53,226 INFO 2025-03-28 16:10:53,226 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-patch-500/stderr.txt" ]; then echo "/results/orders/Reporting-patch-500/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-500/stderr.txt file found" 2025-03-28 16:10:53,226 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:10:54,028 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:10:54,028 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:54,028 DEBUG --- stdout --- 2025-03-28 16:10:54,028 DEBUG /results/orders/Reporting-patch-500/stderr.txt file found 2025-03-28 16:10:54,028 DEBUG --- stderr --- 2025-03-28 16:10:54,028 DEBUG 2025-03-28 16:10:54,028 INFO 2025-03-28 16:10:54,029 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-patch-500/stderr.txt 2025-03-28 16:10:54,830 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:10:54,830 DEBUG --- stdout --- 2025-03-28 16:10:54,830 DEBUG 0 /results/orders/Reporting-patch-500/stderr.txt 2025-03-28 16:10:54,830 DEBUG --- stderr --- 2025-03-28 16:10:54,830 DEBUG 2025-03-28 16:10:54,830 INFO - Download file (size 0k) source : /results/orders/Reporting-patch-500/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/17_Reporting-patch-500/stderr.txt 2025-03-28 16:10:54,831 INFO 2025-03-28 16:10:54,831 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-patch-500/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/17_Reporting-patch-500/stderr.txt 2025-03-28 16:10:54,831 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:10:55,669 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:55,669 DEBUG --- stdout --- 2025-03-28 16:10:55,669 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:10:55,669 DEBUG --- stderr --- 2025-03-28 16:10:55,669 DEBUG 2025-03-28 16:10:55,669 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/17_Reporting-patch-500/stderr.txt is small (0 lines), printing all lines: 2025-03-28 16:10:55,669 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 16:10:55] Reporting-patch-500 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 16:10:55] Reporting-patch-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 PatchManagedUser no spikes found ________________________________________________________________________________ [2025-03-28 16:10:55] Reporting-patch-500 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 1794232, "ok": 1794226, "ko": 6 }, "minResponseTime": { "total": 37, "ok": 54, "ko": 37 }, "maxResponseTime": { "total": 1558, "ok": 1558, "ko": 41 }, "meanResponseTime": { "total": 79, "ok": 79, "ko": 40 }, "standardDeviation": { "total": 14, "ok": 14, "ko": 2 }, "percentiles1": { "total": 75, "ok": 75, "ko": 40 }, "percentiles2": { "total": 88, "ok": 88, "ko": 41 }, "percentiles3": { "total": 94, "ok": 94, "ko": 41 }, "percentiles4": { "total": 117, "ok": 117, "ko": 41 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1794180, "percentage": 99.99710182406734 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 45, "percentage": 0.0025080368647978635 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 1, "percentage": 5.5734152551063624e-05 }, "group4": { "name": "failed", "htmlName": "failed", "count": 6, "percentage": 0.0003344049153063818 }, "meanNumberOfRequestsPerSecond": { "total": 498.3977777777778, "ok": 498.3961111111111, "ko": 0.0016666666666666668 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 60, "ok": 60, "ko": 0 }, "maxResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "meanResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "standardDeviation": { "total": 3, "ok": 3, "ko": 0 }, "percentiles1": { "total": 63, "ok": 63, "ko": 0 }, "percentiles2": { "total": 64, "ok": 64, "ko": 0 }, "percentiles3": { "total": 65, "ok": 65, "ko": 0 }, "percentiles4": { "total": 65, "ok": 65, "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": 117, "ok": 117, "ko": 0 }, "maxResponseTime": { "total": 159, "ok": 159, "ko": 0 }, "meanResponseTime": { "total": 138, "ok": 138, "ko": 0 }, "standardDeviation": { "total": 21, "ok": 21, "ko": 0 }, "percentiles1": { "total": 138, "ok": 138, "ko": 0 }, "percentiles2": { "total": 149, "ok": 149, "ko": 0 }, "percentiles3": { "total": 157, "ok": 157, "ko": 0 }, "percentiles4": { "total": 159, "ok": 159, "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": 68, "ok": 68, "ko": 0 }, "maxResponseTime": { "total": 87, "ok": 87, "ko": 0 }, "meanResponseTime": { "total": 78, "ok": 78, "ko": 0 }, "standardDeviation": { "total": 10, "ok": 10, "ko": 0 }, "percentiles1": { "total": 78, "ok": 78, "ko": 0 }, "percentiles2": { "total": 82, "ok": 82, "ko": 0 }, "percentiles3": { "total": 86, "ok": 86, "ko": 0 }, "percentiles4": { "total": 87, "ok": 87, "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": 54, "ok": 54, "ko": 0 }, "maxResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "meanResponseTime": { "total": 59, "ok": 59, "ko": 0 }, "standardDeviation": { "total": 5, "ok": 5, "ko": 0 }, "percentiles1": { "total": 59, "ok": 59, "ko": 0 }, "percentiles2": { "total": 61, "ok": 61, "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": 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": "PatchManagedUser", "numberOfRequests": { "total": 1794222, "ok": 1794216, "ko": 6 }, "minResponseTime": { "total": 37, "ok": 62, "ko": 37 }, "maxResponseTime": { "total": 1558, "ok": 1558, "ko": 41 }, "meanResponseTime": { "total": 79, "ok": 79, "ko": 40 }, "standardDeviation": { "total": 14, "ok": 14, "ko": 2 }, "percentiles1": { "total": 75, "ok": 75, "ko": 40 }, "percentiles2": { "total": 88, "ok": 88, "ko": 41 }, "percentiles3": { "total": 94, "ok": 94, "ko": 41 }, "percentiles4": { "total": 117, "ok": 117, "ko": 41 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1794170, "percentage": 99.99710180791452 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 45, "percentage": 0.0025080508432066937 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 1, "percentage": 5.573446318237096e-05 }, "group4": { "name": "failed", "htmlName": "failed", "count": 6, "percentage": 0.0003344067790942258 }, "meanNumberOfRequestsPerSecond": { "total": 498.395, "ok": 498.3933333333333, "ko": 0.0016666666666666668 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 498, "avg_response_time": 79, "max_response_time": 1558, "min_response_time": 54, "num_requests": 1794232, "num_requests_pass": 1794226, "num_requests_percent_pass": 100.0 }, "options": "; testMode:open; testCase:idm.ManagedUsersPatch; 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": 65, "total": 65 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "minResponseTime": { "ko": 0, "ok": 60, "total": 60 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 63, "total": 63 }, "percentiles2": { "ko": 0, "ok": 64, "total": 64 }, "percentiles3": { "ko": 0, "ok": 65, "total": 65 }, "percentiles4": { "ko": 0, "ok": 65, "total": 65 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 3, "total": 3 } }, { "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": 159, "total": 159 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 138, "total": 138 }, "minResponseTime": { "ko": 0, "ok": 117, "total": 117 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 138, "total": 138 }, "percentiles2": { "ko": 0, "ok": 149, "total": 149 }, "percentiles3": { "ko": 0, "ok": 157, "total": 157 }, "percentiles4": { "ko": 0, "ok": 159, "total": 159 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 21, "total": 21 } }, { "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": 87, "total": 87 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 78, "total": 78 }, "minResponseTime": { "ko": 0, "ok": 68, "total": 68 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 78, "total": 78 }, "percentiles2": { "ko": 0, "ok": 82, "total": 82 }, "percentiles3": { "ko": 0, "ok": 86, "total": 86 }, "percentiles4": { "ko": 0, "ok": 87, "total": 87 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 10, "total": 10 } }, { "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": 63, "total": 63 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 59, "total": 59 }, "minResponseTime": { "ko": 0, "ok": 54, "total": 54 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 59, "total": 59 }, "percentiles2": { "ko": 0, "ok": 61, "total": 61 }, "percentiles3": { "ko": 0, "ok": 63, "total": 63 }, "percentiles4": { "ko": 0, "ok": 63, "total": 63 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 5, "total": 5 } }, { "group1": { "count": 1794170, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.99710180791452 }, "group2": { "count": 45, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0025080508432066937 }, "group3": { "count": 1, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 5.573446318237096e-05 }, "group4": { "count": 6, "htmlName": "failed", "name": "failed", "percentage": 0.0003344067790942258 }, "maxResponseTime": { "ko": 41, "ok": 1558, "total": 1558 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0016666666666666668, "ok": 498.3933333333333, "total": 498.395 }, "meanResponseTime": { "ko": 40, "ok": 79, "total": 79 }, "minResponseTime": { "ko": 37, "ok": 62, "total": 37 }, "name": "PatchManagedUser", "numberOfRequests": { "ko": 6, "ok": 1794216, "total": 1794222 }, "percentiles1": { "ko": 40, "ok": 75, "total": 75 }, "percentiles2": { "ko": 41, "ok": 88, "total": 88 }, "percentiles3": { "ko": 41, "ok": 94, "total": 94 }, "percentiles4": { "ko": 41, "ok": 117, "total": 117 }, "spikes": [], "standardDeviation": { "ko": 2, "ok": 14, "total": 14 } } ], "global": { "group1": { "count": 1794180, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.99710182406734 }, "group2": { "count": 45, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0025080368647978635 }, "group3": { "count": 1, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 5.5734152551063624e-05 }, "group4": { "count": 6, "htmlName": "failed", "name": "failed", "percentage": 0.0003344049153063818 }, "maxResponseTime": { "ko": 41, "ok": 1558, "total": 1558 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0016666666666666668, "ok": 498.3961111111111, "total": 498.3977777777778 }, "meanResponseTime": { "ko": 40, "ok": 79, "total": 79 }, "minResponseTime": { "ko": 37, "ok": 54, "total": 37 }, "name": "All Requests", "numberOfRequests": { "ko": 6, "ok": 1794226, "total": 1794232 }, "percentiles1": { "ko": 40, "ok": 75, "total": 75 }, "percentiles2": { "ko": 41, "ok": 88, "total": 88 }, "percentiles3": { "ko": 41, "ok": 94, "total": 94 }, "percentiles4": { "ko": 41, "ok": 117, "total": 117 }, "standardDeviation": { "ko": 2, "ok": 14, "total": 14 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 16:10:55] Reporting-patch-500 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 16:10:55] Reporting-patch-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/17_Reporting-patch-500/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 16:10:55] Reporting-patch-500 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped