--Task-- name: Reporting-patch-1500 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'] preceding_task: None options: {'task_name': 'patch-1500'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 16:13:56 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 16:13:56 - 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-1500/stdout.txt Pod stderr file : /results/orders/Reporting-patch-1500/stderr.txt Pod log : /results/orders/Reporting-patch-1500 ________________________________________________________________________________ [2025-03-28 16:13:56] Reporting-patch-1500 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/19_Reporting-patch-1500/order.json { "name": "Reporting-patch-1500", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/patch-1500\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"patch-1500.tar.gz\"", "out-dir": "/results/orders/Reporting-patch-1500" } ________________________________________________________________________________ [2025-03-28 16:13:56] Reporting-patch-1500 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 16:13:56,029 INFO 2025-03-28 16:13:56,029 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:13:56,029 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 16:13:56,243 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:13:56,243 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:56,243 DEBUG --- stdout --- 2025-03-28 16:13:56,243 DEBUG WAITING 2025-03-28 16:13:56,243 DEBUG --- stderr --- 2025-03-28 16:13:56,243 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:13:56] Reporting-patch-1500 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 16:13:56,244 INFO 2025-03-28 16:13:56,244 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:13:56,244 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:13:56,520 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:13:56,520 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:56,520 DEBUG --- stdout --- 2025-03-28 16:13:56,520 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:13:56,520 DEBUG --- stderr --- 2025-03-28 16:13:56,520 DEBUG 2025-03-28 16:13:56,520 INFO 2025-03-28 16:13:56,520 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/19_Reporting-patch-1500/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 16:13:56,520 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:13:57,890 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:57,890 DEBUG --- stdout --- 2025-03-28 16:13:57,890 DEBUG 2025-03-28 16:13:57,890 DEBUG --- stderr --- 2025-03-28 16:13:57,890 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 16:13:57] Reporting-patch-1500 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 16:14:02,891 INFO 2025-03-28 16:14:02,891 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:14:02,891 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 16:14:03,118 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:14:08,337 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:14:13,552 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:14:18,767 INFO [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:14:23,982 INFO [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:14:29,197 INFO [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:14:34,454 INFO [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:14:39,670 INFO [loop_until]: Function succeeded after 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:14:44,884 INFO [loop_until]: Function succeeded after 41s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:14:50,098 INFO [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:14:55,330 INFO [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:00,545 INFO [loop_until]: Function succeeded after 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:05,804 INFO [loop_until]: Function succeeded after 1m 02s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:11,019 INFO [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:16,234 INFO [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:21,449 INFO [loop_until]: Function succeeded after 1m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:26,663 INFO [loop_until]: Function succeeded after 1m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:31,878 INFO [loop_until]: Function succeeded after 1m 28s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:37,096 INFO [loop_until]: Function succeeded after 1m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:42,314 INFO [loop_until]: Function succeeded after 1m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:47,531 INFO [loop_until]: Function succeeded after 1m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:52,745 INFO [loop_until]: Function succeeded after 1m 49s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:15:57,960 INFO [loop_until]: Function succeeded after 1m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:16:03,175 INFO [loop_until]: Function succeeded after 2m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:16:08,390 INFO [loop_until]: Function succeeded after 2m 05s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:16:13,604 INFO [loop_until]: Function succeeded after 2m 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:16:18,863 INFO [loop_until]: Function succeeded after 2m 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:16:24,077 INFO [loop_until]: Function succeeded after 2m 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:16:29,292 INFO [loop_until]: Function succeeded after 2m 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:16:34,507 INFO [loop_until]: Function succeeded after 2m 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:16:39,721 INFO [loop_until]: Function succeeded after 2m 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:16:44,936 INFO [loop_until]: Function succeeded after 2m 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:16:50,204 INFO [loop_until]: Function succeeded after 2m 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:16:55,418 INFO [loop_until]: Function succeeded after 2m 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:17:00,633 INFO [loop_until]: Function succeeded after 2m 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:17:05,848 INFO [loop_until]: Function succeeded after 3m 02s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:17:11,063 INFO [loop_until]: Function succeeded after 3m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:17:16,279 INFO [loop_until]: Function succeeded after 3m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:17:21,496 INFO [loop_until]: Function succeeded after 3m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:17:26,711 INFO [loop_until]: Function succeeded after 3m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:17:31,926 INFO [loop_until]: Function succeeded after 3m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:17:37,140 INFO [loop_until]: Function succeeded after 3m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:17:42,356 INFO [loop_until]: Function succeeded after 3m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:17:47,639 INFO [loop_until]: Function succeeded after 3m 44s (rc=0) - expected pattern found 2025-03-28 16:17:47,639 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:47,639 DEBUG --- stdout --- 2025-03-28 16:17:47,639 DEBUG WAITING 2025-03-28 16:17:47,639 DEBUG --- stderr --- 2025-03-28 16:17:47,639 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 25 0 --:--:-- --:--:-- --:--:-- 25 ________________________________________________________________________________ [2025-03-28 16:17:47] Reporting-patch-1500 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 16:17:47,640 INFO 2025-03-28 16:17:47,640 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:47,640 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:17:47,941 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:17:47,941 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:47,941 DEBUG --- stdout --- 2025-03-28 16:17:47,941 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:17:47,941 DEBUG --- stderr --- 2025-03-28 16:17:47,941 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:17:47,942 INFO 2025-03-28 16:17:47,942 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-1500/patch-1500.tar.gz" ]; then echo "/results/gatling/patch-1500/patch-1500.tar.gz file found"; exit 0; else echo "/results/gatling/patch-1500/patch-1500.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/patch-1500/patch-1500.tar.gz file found" 2025-03-28 16:17:47,942 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:17:48,796 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:17:48,796 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:48,796 DEBUG --- stdout --- 2025-03-28 16:17:48,796 DEBUG /results/gatling/patch-1500/patch-1500.tar.gz file found 2025-03-28 16:17:48,796 DEBUG --- stderr --- 2025-03-28 16:17:48,796 DEBUG 2025-03-28 16:17:48,796 INFO 2025-03-28 16:17:48,797 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-1500/patch-1500.tar.gz 2025-03-28 16:17:49,596 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:17:49,596 DEBUG --- stdout --- 2025-03-28 16:17:49,596 DEBUG 308 /results/gatling/patch-1500/patch-1500.tar.gz 2025-03-28 16:17:49,596 DEBUG --- stderr --- 2025-03-28 16:17:49,596 DEBUG 2025-03-28 16:17:49,596 INFO - Download file (size 308k) source : /results/gatling/patch-1500/patch-1500.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1500.tar.gz 2025-03-28 16:17:49,597 INFO 2025-03-28 16:17:49,597 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/patch-1500/patch-1500.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1500.tar.gz 2025-03-28 16:17:49,597 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:17:50,594 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:50,594 DEBUG --- stdout --- 2025-03-28 16:17:50,594 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:17:50,594 DEBUG --- stderr --- 2025-03-28 16:17:50,594 DEBUG ________________________________________________________________________________ [2025-03-28 16:17:50] Reporting-patch-1500 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 16:17:50,595 INFO 2025-03-28 16:17:50,595 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1500.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/08_patch-1500/gatling 2025-03-28 16:17:50,609 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:17:50,609 DEBUG --- stdout --- 2025-03-28 16:17:50,610 DEBUG 2025-03-28 16:17:50,610 DEBUG --- stderr --- 2025-03-28 16:17:50,610 DEBUG 2025-03-28 16:17:50,610 INFO ________________________________________________________________________________ [2025-03-28 16:17:50] Reporting-patch-1500 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 16:17:50,610 INFO 2025-03-28 16:17:50,610 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:50,610 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:17:50,890 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:17:50,890 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:50,890 DEBUG --- stdout --- 2025-03-28 16:17:50,890 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:17:50,890 DEBUG --- stderr --- 2025-03-28 16:17:50,890 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:17:50,891 INFO 2025-03-28 16:17:50,891 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-1500/rc.txt" ]; then echo "/results/orders/Reporting-patch-1500/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1500/rc.txt file found" 2025-03-28 16:17:50,891 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:17:51,695 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:17:51,695 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:51,695 DEBUG --- stdout --- 2025-03-28 16:17:51,695 DEBUG /results/orders/Reporting-patch-1500/rc.txt file found 2025-03-28 16:17:51,695 DEBUG --- stderr --- 2025-03-28 16:17:51,695 DEBUG 2025-03-28 16:17:51,695 INFO 2025-03-28 16:17:51,696 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-1500/rc.txt 2025-03-28 16:17:52,502 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:17:52,502 DEBUG --- stdout --- 2025-03-28 16:17:52,503 DEBUG 4 /results/orders/Reporting-patch-1500/rc.txt 2025-03-28 16:17:52,503 DEBUG --- stderr --- 2025-03-28 16:17:52,503 DEBUG 2025-03-28 16:17:52,503 INFO - Download file (size 4k) source : /results/orders/Reporting-patch-1500/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/19_Reporting-patch-1500/rc.txt 2025-03-28 16:17:52,503 INFO 2025-03-28 16:17:52,503 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-1500/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/19_Reporting-patch-1500/rc.txt 2025-03-28 16:17:52,503 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:17:53,224 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:53,224 DEBUG --- stdout --- 2025-03-28 16:17:53,224 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:17:53,224 DEBUG --- stderr --- 2025-03-28 16:17:53,224 DEBUG 2025-03-28 16:17:53,224 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/19_Reporting-patch-1500/rc.txt is small (1 lines), printing all lines: 2025-03-28 16:17:53,224 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 16:17:53] Reporting-patch-1500 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:17:53] Reporting-patch-1500 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 16:17:53,465 INFO 2025-03-28 16:17:53,465 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:53,465 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:17:53,744 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:17:53,744 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:53,744 DEBUG --- stdout --- 2025-03-28 16:17:53,744 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:17:53,744 DEBUG --- stderr --- 2025-03-28 16:17:53,744 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:17:53,745 INFO 2025-03-28 16:17:53,745 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-1500/stdout.txt" ]; then echo "/results/orders/Reporting-patch-1500/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1500/stdout.txt file found" 2025-03-28 16:17:53,745 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:17:54,547 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:17:54,547 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:54,547 DEBUG --- stdout --- 2025-03-28 16:17:54,548 DEBUG /results/orders/Reporting-patch-1500/stdout.txt file found 2025-03-28 16:17:54,548 DEBUG --- stderr --- 2025-03-28 16:17:54,548 DEBUG 2025-03-28 16:17:54,548 INFO 2025-03-28 16:17:54,548 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-1500/stdout.txt 2025-03-28 16:17:55,395 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:17:55,396 DEBUG --- stdout --- 2025-03-28 16:17:55,396 DEBUG 8 /results/orders/Reporting-patch-1500/stdout.txt 2025-03-28 16:17:55,396 DEBUG --- stderr --- 2025-03-28 16:17:55,396 DEBUG 2025-03-28 16:17:55,396 INFO - Download file (size 8k) source : /results/orders/Reporting-patch-1500/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/19_Reporting-patch-1500/stdout.txt 2025-03-28 16:17:55,396 INFO 2025-03-28 16:17:55,396 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-1500/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/19_Reporting-patch-1500/stdout.txt 2025-03-28 16:17:55,396 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:17:56,125 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:56,125 DEBUG --- stdout --- 2025-03-28 16:17:56,125 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:17:56,125 DEBUG --- stderr --- 2025-03-28 16:17:56,125 DEBUG 2025-03-28 16:17:56,125 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/19_Reporting-patch-1500/stdout.txt is small (93 lines), printing all lines: 2025-03-28 16:17:56,125 INFO ----- output ----- 2025-03-28 16:13:58,879 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpn1yzs0yd 2025-03-28 16:13:58,880 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/patch-1500/loadtest-20250328124758665" -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/tmpn1yzs0yd/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/tmpn1yzs0yd/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:14:00,711 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 16:14:00,713 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 16:14:00,713 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:14:00,713 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 16:14:00,719 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 16:14:00,719 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 16:14:00,726 |-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:14:00,726 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 16:14:00,727 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 16:14:00,727 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 16:14:00,730 |-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:14:00,734 |-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:14:00,778 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 16:14:00,778 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 16:14:00,816 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 16:14:00,817 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 16:14:00,823 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 16:14:00,843 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 16:14:00,844 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 16:14:00,844 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 16:14:00,844 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 16:14:00,844 |-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 220s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 5375372 (OK=5375359 KO=13 ) > min response time 37 (OK=57 KO=37 ) > max response time 13276 (OK=9010 KO=13276 ) > mean response time 110 (OK=110 KO=3225 ) > std deviation 242 (OK=241 KO=4813 ) > response time 50th percentile 92 (OK=92 KO=42 ) > response time 75th percentile 101 (OK=101 KO=7733 ) > response time 95th percentile 133 (OK=133 KO=11311 ) > response time 99th percentile 290 (OK=290 KO=12883 ) > mean requests/sec 1493.16 (OK=1493.16 KO=0 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 5358848 ( 99.69%) > 800 ms <= t < 1200 ms 553 ( 0.01%) > t >= 1200 ms 15958 ( 0.3%) > failed 13 ( 0%) ---- Errors -------------------------------------------------------------------- > status.find.in(200), but actually found 502 7 (53.85%) > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 3 (23.08%) r 10000ms > status.find.in(200), but actually found 503 2 (15.38%) > j.n.c.ClosedChannelException 1 ( 7.69%) ================================================================================ Reports generated, please open the following file: file:///results/gatling/patch-1500/loadtest-20250328124758665/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 03:43 min [INFO] Finished at: 2025-03-28T16:17:42Z [INFO] ------------------------------------------------------------------------ 2025-03-28 16:17:42,876 INFO Gatling simulation run ended successfully 2025-03-28 16:17:42,876 INFO Creating archive patch-1500.tar.gz with report files 2025-03-28 16:17:42,982 INFO Archive /results/gatling/patch-1500/patch-1500.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 16:17:56] Reporting-patch-1500 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 16:17:56] Reporting-patch-1500 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 16:17:56,126 INFO 2025-03-28 16:17:56,126 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:56,126 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:17:56,407 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:17:56,407 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:56,407 DEBUG --- stdout --- 2025-03-28 16:17:56,407 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:17:56,407 DEBUG --- stderr --- 2025-03-28 16:17:56,407 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:17:56,408 INFO 2025-03-28 16:17:56,408 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-1500/stderr.txt" ]; then echo "/results/orders/Reporting-patch-1500/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1500/stderr.txt file found" 2025-03-28 16:17:56,408 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:17:57,210 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:17:57,210 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:57,210 DEBUG --- stdout --- 2025-03-28 16:17:57,210 DEBUG /results/orders/Reporting-patch-1500/stderr.txt file found 2025-03-28 16:17:57,211 DEBUG --- stderr --- 2025-03-28 16:17:57,211 DEBUG 2025-03-28 16:17:57,211 INFO 2025-03-28 16:17:57,211 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-1500/stderr.txt 2025-03-28 16:17:58,017 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:17:58,017 DEBUG --- stdout --- 2025-03-28 16:17:58,017 DEBUG 0 /results/orders/Reporting-patch-1500/stderr.txt 2025-03-28 16:17:58,017 DEBUG --- stderr --- 2025-03-28 16:17:58,017 DEBUG 2025-03-28 16:17:58,017 INFO - Download file (size 0k) source : /results/orders/Reporting-patch-1500/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/19_Reporting-patch-1500/stderr.txt 2025-03-28 16:17:58,018 INFO 2025-03-28 16:17:58,018 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-1500/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/19_Reporting-patch-1500/stderr.txt 2025-03-28 16:17:58,018 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:17:58,749 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:17:58,749 DEBUG --- stdout --- 2025-03-28 16:17:58,749 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:17:58,749 DEBUG --- stderr --- 2025-03-28 16:17:58,749 DEBUG 2025-03-28 16:17:58,749 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/19_Reporting-patch-1500/stderr.txt is small (0 lines), printing all lines: 2025-03-28 16:17:58,749 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 16:17:58] Reporting-patch-1500 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 16:17:58] Reporting-patch-1500 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/08_patch-1500/gatling/req_patchmanageduse--1542793534.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 16:17:58] Reporting-patch-1500 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 5375372, "ok": 5375359, "ko": 13 }, "minResponseTime": { "total": 37, "ok": 57, "ko": 37 }, "maxResponseTime": { "total": 13276, "ok": 9010, "ko": 13276 }, "meanResponseTime": { "total": 110, "ok": 110, "ko": 3225 }, "standardDeviation": { "total": 242, "ok": 241, "ko": 4813 }, "percentiles1": { "total": 92, "ok": 92, "ko": 42 }, "percentiles2": { "total": 101, "ok": 101, "ko": 7733 }, "percentiles3": { "total": 133, "ok": 133, "ko": 11311 }, "percentiles4": { "total": 290, "ok": 290, "ko": 12883 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 5358848, "percentage": 99.69259801926268 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 553, "percentage": 0.010287660091245779 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 15958, "percentage": 0.29687247691880675 }, "group4": { "name": "failed", "htmlName": "failed", "count": 13, "percentage": 0.00024184372728064217 }, "meanNumberOfRequestsPerSecond": { "total": 1493.158888888889, "ok": 1493.1552777777779, "ko": 0.003611111111111111 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "maxResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "meanResponseTime": { "total": 64, "ok": 64, "ko": 0 }, "standardDeviation": { "total": 1, "ok": 1, "ko": 0 }, "percentiles1": { "total": 64, "ok": 64, "ko": 0 }, "percentiles2": { "total": 65, "ok": 65, "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": 115, "ok": 115, "ko": 0 }, "maxResponseTime": { "total": 118, "ok": 118, "ko": 0 }, "meanResponseTime": { "total": 117, "ok": 117, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 117, "ok": 117, "ko": 0 }, "percentiles2": { "total": 117, "ok": 117, "ko": 0 }, "percentiles3": { "total": 118, "ok": 118, "ko": 0 }, "percentiles4": { "total": 118, "ok": 118, "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": 70, "ok": 70, "ko": 0 }, "maxResponseTime": { "total": 82, "ok": 82, "ko": 0 }, "meanResponseTime": { "total": 76, "ok": 76, "ko": 0 }, "standardDeviation": { "total": 6, "ok": 6, "ko": 0 }, "percentiles1": { "total": 76, "ok": 76, "ko": 0 }, "percentiles2": { "total": 79, "ok": 79, "ko": 0 }, "percentiles3": { "total": 81, "ok": 81, "ko": 0 }, "percentiles4": { "total": 82, "ok": 82, "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": 61, "ok": 61, "ko": 0 }, "maxResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "meanResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "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": "User AccessToken PKCE stage", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 57, "ok": 57, "ko": 0 }, "maxResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "meanResponseTime": { "total": 60, "ok": 60, "ko": 0 }, "standardDeviation": { "total": 3, "ok": 3, "ko": 0 }, "percentiles1": { "total": 60, "ok": 60, "ko": 0 }, "percentiles2": { "total": 62, "ok": 62, "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": 5375362, "ok": 5375349, "ko": 13 }, "minResponseTime": { "total": 37, "ok": 64, "ko": 37 }, "maxResponseTime": { "total": 13276, "ok": 9010, "ko": 13276 }, "meanResponseTime": { "total": 110, "ok": 110, "ko": 3225 }, "standardDeviation": { "total": 242, "ok": 241, "ko": 4813 }, "percentiles1": { "total": 92, "ok": 92, "ko": 42 }, "percentiles2": { "total": 101, "ok": 101, "ko": 7733 }, "percentiles3": { "total": 133, "ok": 133, "ko": 11311 }, "percentiles4": { "total": 290, "ok": 289, "ko": 12883 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 5358838, "percentage": 99.69259744739051 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 553, "percentage": 0.010287679229789548 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 15958, "percentage": 0.29687302920249836 }, "group4": { "name": "failed", "htmlName": "failed", "count": 13, "percentage": 0.00024184417719215934 }, "meanNumberOfRequestsPerSecond": { "total": 1493.1561111111112, "ok": 1493.1525, "ko": 0.003611111111111111 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 1493, "avg_response_time": 110, "max_response_time": 9010, "min_response_time": 57, "num_requests": 5375372, "num_requests_pass": 5375359, "num_requests_percent_pass": 100.0 }, "options": "; testMode:open; testCase:idm.ManagedUsersPatch; duration:3600; throughput:1500", "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": 64, "total": 64 }, "minResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 64, "total": 64 }, "percentiles2": { "ko": 0, "ok": 65, "total": 65 }, "percentiles3": { "ko": 0, "ok": 65, "total": 65 }, "percentiles4": { "ko": 0, "ok": 65, "total": 65 }, "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": 118, "total": 118 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 117, "total": 117 }, "minResponseTime": { "ko": 0, "ok": 115, "total": 115 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 117, "total": 117 }, "percentiles2": { "ko": 0, "ok": 117, "total": 117 }, "percentiles3": { "ko": 0, "ok": 118, "total": 118 }, "percentiles4": { "ko": 0, "ok": 118, "total": 118 }, "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": 82, "total": 82 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 76, "total": 76 }, "minResponseTime": { "ko": 0, "ok": 70, "total": 70 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 76, "total": 76 }, "percentiles2": { "ko": 0, "ok": 79, "total": 79 }, "percentiles3": { "ko": 0, "ok": 81, "total": 81 }, "percentiles4": { "ko": 0, "ok": 82, "total": 82 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 6, "total": 6 } }, { "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": 61, "total": 61 }, "name": "User Authorize PKCE stage", "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": 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": 63, "total": 63 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 60, "total": 60 }, "minResponseTime": { "ko": 0, "ok": 57, "total": 57 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 60, "total": 60 }, "percentiles2": { "ko": 0, "ok": 62, "total": 62 }, "percentiles3": { "ko": 0, "ok": 63, "total": 63 }, "percentiles4": { "ko": 0, "ok": 63, "total": 63 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 3, "total": 3 } }, { "group1": { "count": 5358838, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.69259744739051 }, "group2": { "count": 553, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.010287679229789548 }, "group3": { "count": 15958, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.29687302920249836 }, "group4": { "count": 13, "htmlName": "failed", "name": "failed", "percentage": 0.00024184417719215934 }, "maxResponseTime": { "ko": 13276, "ok": 9010, "total": 13276 }, "meanNumberOfRequestsPerSecond": { "ko": 0.003611111111111111, "ok": 1493.1525, "total": 1493.1561111111112 }, "meanResponseTime": { "ko": 3225, "ok": 110, "total": 110 }, "minResponseTime": { "ko": 37, "ok": 64, "total": 37 }, "name": "PatchManagedUser", "numberOfRequests": { "ko": 13, "ok": 5375349, "total": 5375362 }, "percentiles1": { "ko": 42, "ok": 92, "total": 92 }, "percentiles2": { "ko": 7733, "ok": 101, "total": 101 }, "percentiles3": { "ko": 11311, "ok": 133, "total": 133 }, "percentiles4": { "ko": 12883, "ok": 289, "total": 290 }, "spikes": [], "standardDeviation": { "ko": 4813, "ok": 241, "total": 242 } } ], "global": { "group1": { "count": 5358848, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.69259801926268 }, "group2": { "count": 553, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.010287660091245779 }, "group3": { "count": 15958, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.29687247691880675 }, "group4": { "count": 13, "htmlName": "failed", "name": "failed", "percentage": 0.00024184372728064217 }, "maxResponseTime": { "ko": 13276, "ok": 9010, "total": 13276 }, "meanNumberOfRequestsPerSecond": { "ko": 0.003611111111111111, "ok": 1493.1552777777779, "total": 1493.158888888889 }, "meanResponseTime": { "ko": 3225, "ok": 110, "total": 110 }, "minResponseTime": { "ko": 37, "ok": 57, "total": 37 }, "name": "All Requests", "numberOfRequests": { "ko": 13, "ok": 5375359, "total": 5375372 }, "percentiles1": { "ko": 42, "ok": 92, "total": 92 }, "percentiles2": { "ko": 7733, "ok": 101, "total": 101 }, "percentiles3": { "ko": 11311, "ok": 133, "total": 133 }, "percentiles4": { "ko": 12883, "ok": 290, "total": 290 }, "standardDeviation": { "ko": 4813, "ok": 241, "total": 242 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 16:17:58] Reporting-patch-1500 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 16:17:58] Reporting-patch-1500 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/19_Reporting-patch-1500/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 16:17:58] Reporting-patch-1500 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped