--Task-- name: Reporting-patch-1700 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', 'Reporting-patch-500', 'Reporting-patch-1000', 'Reporting-patch-1500'] preceding_task: None options: {'task_name': 'patch-1700'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 16:17:58 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 16:17:58 - INFO: timeout was set to GatlingResultTask default (half of global_duration or 3600 seconds (whatever is higher)), because it was unset Pod stdout file : /results/orders/Reporting-patch-1700/stdout.txt Pod stderr file : /results/orders/Reporting-patch-1700/stderr.txt Pod log : /results/orders/Reporting-patch-1700 ________________________________________________________________________________ [2025-03-28 16:17:58] Reporting-patch-1700 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/20_Reporting-patch-1700/order.json { "name": "Reporting-patch-1700", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/patch-1700\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"patch-1700.tar.gz\"", "out-dir": "/results/orders/Reporting-patch-1700" } ________________________________________________________________________________ [2025-03-28 16:17:58] Reporting-patch-1700 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 16:17:58,759 INFO 2025-03-28 16:17:58,759 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:17:58,759 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 16:17:58,976 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:17:58,976 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:58,976 DEBUG --- stdout --- 2025-03-28 16:17:58,977 DEBUG WAITING 2025-03-28 16:17:58,977 DEBUG --- stderr --- 2025-03-28 16:17:58,977 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:17:58] Reporting-patch-1700 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 16:17:58,977 INFO 2025-03-28 16:17:58,977 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:17:58,977 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:17:59,254 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:17:59,255 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:59,255 DEBUG --- stdout --- 2025-03-28 16:17:59,255 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:17:59,255 DEBUG --- stderr --- 2025-03-28 16:17:59,255 DEBUG 2025-03-28 16:17:59,255 INFO 2025-03-28 16:17:59,255 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/20_Reporting-patch-1700/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 16:17:59,255 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:18:00,623 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:18:00,623 DEBUG --- stdout --- 2025-03-28 16:18:00,623 DEBUG 2025-03-28 16:18:00,623 DEBUG --- stderr --- 2025-03-28 16:18:00,623 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 16:18:00] Reporting-patch-1700 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 16:18:05,624 INFO 2025-03-28 16:18:05,624 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:18:05,624 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 16:18:05,841 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:18:11,062 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:18:16,279 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:18:21,538 INFO [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:18:26,753 INFO [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:18:31,969 INFO [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:18:37,184 INFO [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:18:42,398 INFO [loop_until]: Function succeeded after 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:18:47,613 INFO [loop_until]: Function succeeded after 41s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:18:52,830 INFO [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:18:58,097 INFO [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:19:03,313 INFO [loop_until]: Function succeeded after 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:19:08,528 INFO [loop_until]: Function succeeded after 1m 02s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:19:13,742 INFO [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:19:18,957 INFO [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:19:24,172 INFO [loop_until]: Function succeeded after 1m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:19:29,431 INFO [loop_until]: Function succeeded after 1m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:19:34,647 INFO [loop_until]: Function succeeded after 1m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:19:39,862 INFO [loop_until]: Function succeeded after 1m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:19:45,076 INFO [loop_until]: Function succeeded after 1m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:19:50,291 INFO [loop_until]: Function succeeded after 1m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:19:55,506 INFO [loop_until]: Function succeeded after 1m 49s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:00,728 INFO [loop_until]: Function succeeded after 1m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:05,942 INFO [loop_until]: Function succeeded after 2m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:11,158 INFO [loop_until]: Function succeeded after 2m 05s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:16,373 INFO [loop_until]: Function succeeded after 2m 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:21,587 INFO [loop_until]: Function succeeded after 2m 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:26,802 INFO [loop_until]: Function succeeded after 2m 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:32,019 INFO [loop_until]: Function succeeded after 2m 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:37,302 INFO [loop_until]: Function succeeded after 2m 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:42,518 INFO [loop_until]: Function succeeded after 2m 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:47,732 INFO [loop_until]: Function succeeded after 2m 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:52,947 INFO [loop_until]: Function succeeded after 2m 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:20:58,162 INFO [loop_until]: Function succeeded after 2m 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:21:03,377 INFO [loop_until]: Function succeeded after 2m 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:21:08,646 INFO [loop_until]: Function succeeded after 3m 03s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:21:13,860 INFO [loop_until]: Function succeeded after 3m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:21:20,101 INFO [loop_until]: Function succeeded after 3m 14s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:21:25,316 INFO [loop_until]: Function succeeded after 3m 19s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:21:30,532 INFO [loop_until]: Function succeeded after 3m 24s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:21:35,746 INFO [loop_until]: Function succeeded after 3m 30s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:21:41,003 INFO [loop_until]: Function succeeded after 3m 35s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:21:46,218 INFO [loop_until]: Function succeeded after 3m 40s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:21:51,433 INFO [loop_until]: Function succeeded after 3m 45s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:21:56,647 INFO [loop_until]: Function succeeded after 3m 51s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:01,862 INFO [loop_until]: Function succeeded after 3m 56s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:07,076 INFO [loop_until]: Function succeeded after 4m 01s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:12,344 INFO [loop_until]: Function succeeded after 4m 06s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:17,560 INFO [loop_until]: Function succeeded after 4m 11s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:22,774 INFO [loop_until]: Function succeeded after 4m 17s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:27,989 INFO [loop_until]: Function succeeded after 4m 22s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:33,205 INFO [loop_until]: Function succeeded after 4m 27s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:38,420 INFO [loop_until]: Function succeeded after 4m 32s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:43,637 INFO [loop_until]: Function succeeded after 4m 38s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:48,904 INFO [loop_until]: Function succeeded after 4m 43s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:54,119 INFO [loop_until]: Function succeeded after 4m 48s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:22:59,334 INFO [loop_until]: Function succeeded after 4m 53s (rc=0) - expected pattern found 2025-03-28 16:22:59,334 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:22:59,334 DEBUG --- stdout --- 2025-03-28 16:22:59,334 DEBUG WAITING 2025-03-28 16:22:59,334 DEBUG --- stderr --- 2025-03-28 16:22:59,334 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:22:59] Reporting-patch-1700 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 16:22:59,335 INFO 2025-03-28 16:22:59,335 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:22:59,335 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:22:59,637 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:22:59,638 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:22:59,638 DEBUG --- stdout --- 2025-03-28 16:22:59,638 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:22:59,638 DEBUG --- stderr --- 2025-03-28 16:22:59,638 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:22:59,639 INFO 2025-03-28 16:22:59,639 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-1700/patch-1700.tar.gz" ]; then echo "/results/gatling/patch-1700/patch-1700.tar.gz file found"; exit 0; else echo "/results/gatling/patch-1700/patch-1700.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/patch-1700/patch-1700.tar.gz file found" 2025-03-28 16:22:59,639 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:23:00,489 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:23:00,489 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:00,489 DEBUG --- stdout --- 2025-03-28 16:23:00,489 DEBUG /results/gatling/patch-1700/patch-1700.tar.gz file found 2025-03-28 16:23:00,489 DEBUG --- stderr --- 2025-03-28 16:23:00,489 DEBUG 2025-03-28 16:23:00,489 INFO 2025-03-28 16:23:00,490 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-1700/patch-1700.tar.gz 2025-03-28 16:23:01,295 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:23:01,295 DEBUG --- stdout --- 2025-03-28 16:23:01,295 DEBUG 312 /results/gatling/patch-1700/patch-1700.tar.gz 2025-03-28 16:23:01,295 DEBUG --- stderr --- 2025-03-28 16:23:01,295 DEBUG 2025-03-28 16:23:01,295 INFO - Download file (size 312k) source : /results/gatling/patch-1700/patch-1700.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1700.tar.gz 2025-03-28 16:23:01,296 INFO 2025-03-28 16:23:01,297 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/patch-1700/patch-1700.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1700.tar.gz 2025-03-28 16:23:01,297 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:23:02,328 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:02,328 DEBUG --- stdout --- 2025-03-28 16:23:02,328 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:23:02,328 DEBUG --- stderr --- 2025-03-28 16:23:02,328 DEBUG ________________________________________________________________________________ [2025-03-28 16:23:02] Reporting-patch-1700 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 16:23:02,329 INFO 2025-03-28 16:23:02,329 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1700.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/09_patch-1700/gatling 2025-03-28 16:23:02,343 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:23:02,343 DEBUG --- stdout --- 2025-03-28 16:23:02,343 DEBUG 2025-03-28 16:23:02,343 DEBUG --- stderr --- 2025-03-28 16:23:02,343 DEBUG 2025-03-28 16:23:02,343 INFO ________________________________________________________________________________ [2025-03-28 16:23:02] Reporting-patch-1700 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 16:23:02,344 INFO 2025-03-28 16:23:02,344 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:23:02,344 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:23:02,623 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:23:02,623 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:02,623 DEBUG --- stdout --- 2025-03-28 16:23:02,623 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:23:02,623 DEBUG --- stderr --- 2025-03-28 16:23:02,623 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:23:02,624 INFO 2025-03-28 16:23:02,624 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-1700/rc.txt" ]; then echo "/results/orders/Reporting-patch-1700/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1700/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1700/rc.txt file found" 2025-03-28 16:23:02,624 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:23:03,420 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:23:03,421 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:03,421 DEBUG --- stdout --- 2025-03-28 16:23:03,421 DEBUG /results/orders/Reporting-patch-1700/rc.txt file found 2025-03-28 16:23:03,421 DEBUG --- stderr --- 2025-03-28 16:23:03,421 DEBUG 2025-03-28 16:23:03,421 INFO 2025-03-28 16:23:03,421 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-1700/rc.txt 2025-03-28 16:23:04,217 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:23:04,217 DEBUG --- stdout --- 2025-03-28 16:23:04,217 DEBUG 4 /results/orders/Reporting-patch-1700/rc.txt 2025-03-28 16:23:04,217 DEBUG --- stderr --- 2025-03-28 16:23:04,217 DEBUG 2025-03-28 16:23:04,217 INFO - Download file (size 4k) source : /results/orders/Reporting-patch-1700/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/20_Reporting-patch-1700/rc.txt 2025-03-28 16:23:04,218 INFO 2025-03-28 16:23:04,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-1700/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/20_Reporting-patch-1700/rc.txt 2025-03-28 16:23:04,218 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:23:04,981 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:04,981 DEBUG --- stdout --- 2025-03-28 16:23:04,981 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:23:04,981 DEBUG --- stderr --- 2025-03-28 16:23:04,981 DEBUG 2025-03-28 16:23:04,982 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/20_Reporting-patch-1700/rc.txt is small (1 lines), printing all lines: 2025-03-28 16:23:04,982 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 16:23:04] Reporting-patch-1700 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:23:05] Reporting-patch-1700 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 16:23:05,220 INFO 2025-03-28 16:23:05,220 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:23:05,220 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:23:05,497 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:23:05,497 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:05,497 DEBUG --- stdout --- 2025-03-28 16:23:05,497 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:23:05,497 DEBUG --- stderr --- 2025-03-28 16:23:05,497 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:23:05,498 INFO 2025-03-28 16:23:05,498 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-1700/stdout.txt" ]; then echo "/results/orders/Reporting-patch-1700/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1700/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1700/stdout.txt file found" 2025-03-28 16:23:05,498 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:23:06,291 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:23:06,291 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:06,291 DEBUG --- stdout --- 2025-03-28 16:23:06,291 DEBUG /results/orders/Reporting-patch-1700/stdout.txt file found 2025-03-28 16:23:06,291 DEBUG --- stderr --- 2025-03-28 16:23:06,292 DEBUG 2025-03-28 16:23:06,292 INFO 2025-03-28 16:23:06,292 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-1700/stdout.txt 2025-03-28 16:23:07,092 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:23:07,092 DEBUG --- stdout --- 2025-03-28 16:23:07,092 DEBUG 8 /results/orders/Reporting-patch-1700/stdout.txt 2025-03-28 16:23:07,092 DEBUG --- stderr --- 2025-03-28 16:23:07,092 DEBUG 2025-03-28 16:23:07,092 INFO - Download file (size 8k) source : /results/orders/Reporting-patch-1700/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/20_Reporting-patch-1700/stdout.txt 2025-03-28 16:23:07,093 INFO 2025-03-28 16:23:07,093 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-1700/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/20_Reporting-patch-1700/stdout.txt 2025-03-28 16:23:07,093 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:23:07,814 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:07,814 DEBUG --- stdout --- 2025-03-28 16:23:07,814 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:23:07,814 DEBUG --- stderr --- 2025-03-28 16:23:07,814 DEBUG 2025-03-28 16:23:07,815 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/20_Reporting-patch-1700/stdout.txt is small (97 lines), printing all lines: 2025-03-28 16:23:07,815 INFO ----- output ----- 2025-03-28 16:18:01,693 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmphr81s0a8 2025-03-28 16:18:01,695 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/patch-1700/loadtest-20250328134839968" -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/tmphr81s0a8/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/tmphr81s0a8/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:18:03,471 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 16:18:03,473 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 16:18:03,473 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:18:03,473 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 16:18:03,479 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 16:18:03,479 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 16:18:03,487 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 7 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY 16:18:03,487 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 16:18:03,487 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 16:18:03,488 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 16:18:03,490 |-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:18:03,495 |-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:18:03,539 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 16:18:03,539 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 16:18:03,577 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 16:18:03,577 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 16:18:03,583 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 16:18:03,603 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 16:18:03,603 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 16:18:03,604 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 16:18:03,604 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 16:18:03,604 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 117 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY Parsing log file(s)... Parsing log file(s) done in 291s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 6090550 (OK=6090526 KO=24 ) > min response time 38 (OK=59 KO=38 ) > max response time 60000 (OK=6447 KO=60000 ) > mean response time 118 (OK=118 KO=9661 ) > std deviation 76 (OK=66 KO=15899 ) > response time 50th percentile 106 (OK=106 KO=10000 ) > response time 75th percentile 123 (OK=123 KO=10002 ) > response time 95th percentile 181 (OK=181 KO=52537 ) > response time 99th percentile 362 (OK=362 KO=60000 ) > mean requests/sec 1691.82 (OK=1691.81 KO=0.01 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 6082140 ( 99.86%) > 800 ms <= t < 1200 ms 6065 ( 0.1%) > t >= 1200 ms 2321 ( 0.04%) > failed 24 ( 0%) ---- Errors -------------------------------------------------------------------- > status.find.in(200), but actually found 502 9 (37.50%) > i.n.c.ConnectTimeoutException: connection timed out after 1000 4 (16.67%) 0 ms: openam-perf-200m.forgeblocks.com/34.107.203.37:443 > j.i.IOException: Premature close 4 (16.67%) > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 3 (12.50%) r 10000ms > status.find.in(200), but actually found 503 2 ( 8.33%) > Request timeout to openam-perf-200m.forgeblocks.com/34.107.203 2 ( 8.33%) .37:443 after 60000 ms ================================================================================ Reports generated, please open the following file: file:///results/gatling/patch-1700/loadtest-20250328134839968/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 04:53 min [INFO] Finished at: 2025-03-28T16:22:56Z [INFO] ------------------------------------------------------------------------ 2025-03-28 16:22:56,318 INFO Gatling simulation run ended successfully 2025-03-28 16:22:56,318 INFO Creating archive patch-1700.tar.gz with report files 2025-03-28 16:22:56,428 INFO Archive /results/gatling/patch-1700/patch-1700.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 16:23:07] Reporting-patch-1700 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 16:23:07] Reporting-patch-1700 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 16:23:07,816 INFO 2025-03-28 16:23:07,816 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:23:07,816 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:23:08,093 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:23:08,093 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:08,093 DEBUG --- stdout --- 2025-03-28 16:23:08,094 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:23:08,094 DEBUG --- stderr --- 2025-03-28 16:23:08,094 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:23:08,095 INFO 2025-03-28 16:23:08,095 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-1700/stderr.txt" ]; then echo "/results/orders/Reporting-patch-1700/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1700/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1700/stderr.txt file found" 2025-03-28 16:23:08,095 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:23:08,992 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:23:08,992 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:08,993 DEBUG --- stdout --- 2025-03-28 16:23:08,993 DEBUG /results/orders/Reporting-patch-1700/stderr.txt file found 2025-03-28 16:23:08,993 DEBUG --- stderr --- 2025-03-28 16:23:08,993 DEBUG 2025-03-28 16:23:08,993 INFO 2025-03-28 16:23:08,993 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-1700/stderr.txt 2025-03-28 16:23:09,798 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:23:09,798 DEBUG --- stdout --- 2025-03-28 16:23:09,798 DEBUG 0 /results/orders/Reporting-patch-1700/stderr.txt 2025-03-28 16:23:09,798 DEBUG --- stderr --- 2025-03-28 16:23:09,798 DEBUG 2025-03-28 16:23:09,798 INFO - Download file (size 0k) source : /results/orders/Reporting-patch-1700/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/20_Reporting-patch-1700/stderr.txt 2025-03-28 16:23:09,799 INFO 2025-03-28 16:23:09,799 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-1700/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/20_Reporting-patch-1700/stderr.txt 2025-03-28 16:23:09,799 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:23:10,563 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:10,563 DEBUG --- stdout --- 2025-03-28 16:23:10,564 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:23:10,564 DEBUG --- stderr --- 2025-03-28 16:23:10,564 DEBUG 2025-03-28 16:23:10,564 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/20_Reporting-patch-1700/stderr.txt is small (0 lines), printing all lines: 2025-03-28 16:23:10,564 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 16:23:10] Reporting-patch-1700 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 16:23:10] Reporting-patch-1700 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 [warn] responseTimePercentiles was not found in /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/09_patch-1700/gatling/req_patchmanageduse--1542793534.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 16:23:10] Reporting-patch-1700 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 6090550, "ok": 6090526, "ko": 24 }, "minResponseTime": { "total": 38, "ok": 59, "ko": 38 }, "maxResponseTime": { "total": 60000, "ok": 6447, "ko": 60000 }, "meanResponseTime": { "total": 118, "ok": 118, "ko": 9661 }, "standardDeviation": { "total": 76, "ok": 66, "ko": 15899 }, "percentiles1": { "total": 106, "ok": 106, "ko": 10000 }, "percentiles2": { "total": 123, "ok": 123, "ko": 10002 }, "percentiles3": { "total": 181, "ok": 181, "ko": 52537 }, "percentiles4": { "total": 362, "ok": 362, "ko": 60000 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 6082140, "percentage": 99.86191723243385 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 6065, "percentage": 0.09958049765620511 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 2321, "percentage": 0.0381082168277085 }, "group4": { "name": "failed", "htmlName": "failed", "count": 24, "percentage": 0.0003940530822339526 }, "meanNumberOfRequestsPerSecond": { "total": 1691.8194444444443, "ok": 1691.8127777777777, "ko": 0.006666666666666667 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "maxResponseTime": { "total": 67, "ok": 67, "ko": 0 }, "meanResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 65, "ok": 65, "ko": 0 }, "percentiles2": { "total": 66, "ok": 66, "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": "restLoginUsernamePasswordCallback", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 129, "ok": 129, "ko": 0 }, "maxResponseTime": { "total": 132, "ok": 132, "ko": 0 }, "meanResponseTime": { "total": 131, "ok": 131, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 131, "ok": 131, "ko": 0 }, "percentiles2": { "total": 131, "ok": 131, "ko": 0 }, "percentiles3": { "total": 132, "ok": 132, "ko": 0 }, "percentiles4": { "total": 132, "ok": 132, "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": 75, "ok": 75, "ko": 0 }, "maxResponseTime": { "total": 102, "ok": 102, "ko": 0 }, "meanResponseTime": { "total": 89, "ok": 89, "ko": 0 }, "standardDeviation": { "total": 14, "ok": 14, "ko": 0 }, "percentiles1": { "total": 89, "ok": 89, "ko": 0 }, "percentiles2": { "total": 95, "ok": 95, "ko": 0 }, "percentiles3": { "total": 101, "ok": 101, "ko": 0 }, "percentiles4": { "total": 102, "ok": 102, "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": 63, "ok": 63, "ko": 0 }, "maxResponseTime": { "total": 67, "ok": 67, "ko": 0 }, "meanResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 65, "ok": 65, "ko": 0 }, "percentiles2": { "total": 66, "ok": 66, "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": 59, "ok": 59, "ko": 0 }, "maxResponseTime": { "total": 67, "ok": 67, "ko": 0 }, "meanResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "standardDeviation": { "total": 4, "ok": 4, "ko": 0 }, "percentiles1": { "total": 63, "ok": 63, "ko": 0 }, "percentiles2": { "total": 65, "ok": 65, "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": "PatchManagedUser", "numberOfRequests": { "total": 6090540, "ok": 6090516, "ko": 24 }, "minResponseTime": { "total": 38, "ok": 64, "ko": 38 }, "maxResponseTime": { "total": 60000, "ok": 6447, "ko": 60000 }, "meanResponseTime": { "total": 118, "ok": 118, "ko": 9661 }, "standardDeviation": { "total": 76, "ok": 66, "ko": 15899 }, "percentiles1": { "total": 106, "ok": 106, "ko": 10000 }, "percentiles2": { "total": 123, "ok": 123, "ko": 10002 }, "percentiles3": { "total": 181, "ok": 181, "ko": 52537 }, "percentiles4": { "total": 362, "ok": 362, "ko": 60000 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 6082130, "percentage": 99.86191700571706 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 6065, "percentage": 0.09958066115648202 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 2321, "percentage": 0.03810827939722914 }, "group4": { "name": "failed", "htmlName": "failed", "count": 24, "percentage": 0.00039405372922597995 }, "meanNumberOfRequestsPerSecond": { "total": 1691.8166666666666, "ok": 1691.81, "ko": 0.006666666666666667 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 1692, "avg_response_time": 118, "max_response_time": 6447, "min_response_time": 59, "num_requests": 6090550, "num_requests_pass": 6090526, "num_requests_percent_pass": 100.0 }, "options": "; testMode:open; testCase:idm.ManagedUsersPatch; duration:3600; throughput:1700", "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": 67, "total": 67 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 65, "total": 65 }, "minResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "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": 67, "total": 67 }, "percentiles4": { "ko": 0, "ok": 67, "total": 67 }, "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": 132, "total": 132 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 131, "total": 131 }, "minResponseTime": { "ko": 0, "ok": 129, "total": 129 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 131, "total": 131 }, "percentiles2": { "ko": 0, "ok": 131, "total": 131 }, "percentiles3": { "ko": 0, "ok": 132, "total": 132 }, "percentiles4": { "ko": 0, "ok": 132, "total": 132 }, "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": 102, "total": 102 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 89, "total": 89 }, "minResponseTime": { "ko": 0, "ok": 75, "total": 75 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 89, "total": 89 }, "percentiles2": { "ko": 0, "ok": 95, "total": 95 }, "percentiles3": { "ko": 0, "ok": 101, "total": 101 }, "percentiles4": { "ko": 0, "ok": 102, "total": 102 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 14, "total": 14 } }, { "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": 65, "total": 65 }, "minResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "name": "User Authorize PKCE stage", "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": 67, "total": 67 }, "percentiles4": { "ko": 0, "ok": 67, "total": 67 }, "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": 67, "total": 67 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "minResponseTime": { "ko": 0, "ok": 59, "total": 59 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 63, "total": 63 }, "percentiles2": { "ko": 0, "ok": 65, "total": 65 }, "percentiles3": { "ko": 0, "ok": 67, "total": 67 }, "percentiles4": { "ko": 0, "ok": 67, "total": 67 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 4, "total": 4 } }, { "group1": { "count": 6082130, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.86191700571706 }, "group2": { "count": 6065, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.09958066115648202 }, "group3": { "count": 2321, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.03810827939722914 }, "group4": { "count": 24, "htmlName": "failed", "name": "failed", "percentage": 0.00039405372922597995 }, "maxResponseTime": { "ko": 60000, "ok": 6447, "total": 60000 }, "meanNumberOfRequestsPerSecond": { "ko": 0.006666666666666667, "ok": 1691.81, "total": 1691.8166666666666 }, "meanResponseTime": { "ko": 9661, "ok": 118, "total": 118 }, "minResponseTime": { "ko": 38, "ok": 64, "total": 38 }, "name": "PatchManagedUser", "numberOfRequests": { "ko": 24, "ok": 6090516, "total": 6090540 }, "percentiles1": { "ko": 10000, "ok": 106, "total": 106 }, "percentiles2": { "ko": 10002, "ok": 123, "total": 123 }, "percentiles3": { "ko": 52537, "ok": 181, "total": 181 }, "percentiles4": { "ko": 60000, "ok": 362, "total": 362 }, "spikes": [], "standardDeviation": { "ko": 15899, "ok": 66, "total": 76 } } ], "global": { "group1": { "count": 6082140, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.86191723243385 }, "group2": { "count": 6065, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.09958049765620511 }, "group3": { "count": 2321, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0381082168277085 }, "group4": { "count": 24, "htmlName": "failed", "name": "failed", "percentage": 0.0003940530822339526 }, "maxResponseTime": { "ko": 60000, "ok": 6447, "total": 60000 }, "meanNumberOfRequestsPerSecond": { "ko": 0.006666666666666667, "ok": 1691.8127777777777, "total": 1691.8194444444443 }, "meanResponseTime": { "ko": 9661, "ok": 118, "total": 118 }, "minResponseTime": { "ko": 38, "ok": 59, "total": 38 }, "name": "All Requests", "numberOfRequests": { "ko": 24, "ok": 6090526, "total": 6090550 }, "percentiles1": { "ko": 10000, "ok": 106, "total": 106 }, "percentiles2": { "ko": 10002, "ok": 123, "total": 123 }, "percentiles3": { "ko": 52537, "ok": 181, "total": 181 }, "percentiles4": { "ko": 60000, "ok": 362, "total": 362 }, "standardDeviation": { "ko": 15899, "ok": 66, "total": 76 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 16:23:10] Reporting-patch-1700 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 16:23:10] Reporting-patch-1700 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/20_Reporting-patch-1700/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 16:23:10] Reporting-patch-1700 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped