--Task-- name: Reporting-update-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'] preceding_task: None options: {'task_name': 'update-1500'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 15:56:12 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 15:56:12 - INFO: timeout was set to GatlingResultTask default (half of global_duration or 3600 seconds (whatever is higher)), because it was unset Pod stdout file : /results/orders/Reporting-update-1500/stdout.txt Pod stderr file : /results/orders/Reporting-update-1500/stderr.txt Pod log : /results/orders/Reporting-update-1500 ________________________________________________________________________________ [2025-03-28 15:56:12] Reporting-update-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/14_Reporting-update-1500/order.json { "name": "Reporting-update-1500", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/update-1500\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"update-1500.tar.gz\"", "out-dir": "/results/orders/Reporting-update-1500" } ________________________________________________________________________________ [2025-03-28 15:56:12] Reporting-update-1500 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 15:56:12,616 INFO 2025-03-28 15:56:12,616 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 15:56:12,616 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 15:56:12,830 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 15:56:12,831 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:56:12,831 DEBUG --- stdout --- 2025-03-28 15:56:12,831 DEBUG WAITING 2025-03-28 15:56:12,831 DEBUG --- stderr --- 2025-03-28 15:56:12,831 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 15:56:12] Reporting-update-1500 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 15:56:12,831 INFO 2025-03-28 15:56:12,831 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2025-03-28 15:56:12,831 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 15:56:13,108 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 15:56:13,108 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:56:13,108 DEBUG --- stdout --- 2025-03-28 15:56:13,108 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 15:56:13,108 DEBUG --- stderr --- 2025-03-28 15:56:13,108 DEBUG 2025-03-28 15:56:13,108 INFO 2025-03-28 15:56:13,108 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/14_Reporting-update-1500/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 15:56:13,108 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 15:56:14,466 INFO [loop_until]: OK (rc = 0) 2025-03-28 15:56:14,466 DEBUG --- stdout --- 2025-03-28 15:56:14,466 DEBUG 2025-03-28 15:56:14,466 DEBUG --- stderr --- 2025-03-28 15:56:14,466 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 15:56:14] Reporting-update-1500 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 15:56:19,466 INFO 2025-03-28 15:56:19,467 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 15:56:19,467 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 15:56:19,682 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:56:24,966 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:56:30,181 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:56:35,396 INFO [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:56:40,611 INFO [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:56:45,826 INFO [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:56:51,041 INFO [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:56:56,300 INFO [loop_until]: Function succeeded after 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:01,515 INFO [loop_until]: Function succeeded after 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:06,731 INFO [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:11,946 INFO [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:17,161 INFO [loop_until]: Function succeeded after 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:22,376 INFO [loop_until]: Function succeeded after 1m 02s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:27,593 INFO [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:32,876 INFO [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:38,091 INFO [loop_until]: Function succeeded after 1m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:43,349 INFO [loop_until]: Function succeeded after 1m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:48,564 INFO [loop_until]: Function succeeded after 1m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:53,779 INFO [loop_until]: Function succeeded after 1m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:57:58,993 INFO [loop_until]: Function succeeded after 1m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:58:04,211 INFO [loop_until]: Function succeeded after 1m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:58:09,468 INFO [loop_until]: Function succeeded after 1m 50s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:58:14,683 INFO [loop_until]: Function succeeded after 1m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:58:19,898 INFO [loop_until]: Function succeeded after 2m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:58:25,113 INFO [loop_until]: Function succeeded after 2m 05s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:58:30,328 INFO [loop_until]: Function succeeded after 2m 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:58:35,542 INFO [loop_until]: Function succeeded after 2m 16s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:58:40,811 INFO [loop_until]: Function succeeded after 2m 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:58:46,026 INFO [loop_until]: Function succeeded after 2m 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:58:51,241 INFO [loop_until]: Function succeeded after 2m 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:58:56,456 INFO [loop_until]: Function succeeded after 2m 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:01,676 INFO [loop_until]: Function succeeded after 2m 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:06,891 INFO [loop_until]: Function succeeded after 2m 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:12,160 INFO [loop_until]: Function succeeded after 2m 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:17,374 INFO [loop_until]: Function succeeded after 2m 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:22,589 INFO [loop_until]: Function succeeded after 3m 03s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:27,804 INFO [loop_until]: Function succeeded after 3m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:33,019 INFO [loop_until]: Function succeeded after 3m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:38,234 INFO [loop_until]: Function succeeded after 3m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:43,517 INFO [loop_until]: Function succeeded after 3m 24s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:48,732 INFO [loop_until]: Function succeeded after 3m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:53,947 INFO [loop_until]: Function succeeded after 3m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 15:59:59,161 INFO [loop_until]: Function succeeded after 3m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:00:04,376 INFO [loop_until]: Function succeeded after 3m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:00:09,591 INFO [loop_until]: Function succeeded after 3m 50s (rc=0) - expected pattern found 2025-03-28 16:00:09,591 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:09,591 DEBUG --- stdout --- 2025-03-28 16:00:09,591 DEBUG WAITING 2025-03-28 16:00:09,591 DEBUG --- stderr --- 2025-03-28 16:00:09,591 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:00:09] Reporting-update-1500 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 16:00:09,591 INFO 2025-03-28 16:00:09,592 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:00:09,592 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:00:09,894 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:00:09,894 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:09,894 DEBUG --- stdout --- 2025-03-28 16:00:09,894 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:00:09,894 DEBUG --- stderr --- 2025-03-28 16:00:09,894 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:00:09,895 INFO 2025-03-28 16:00:09,895 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/gatling/update-1500/update-1500.tar.gz" ]; then echo "/results/gatling/update-1500/update-1500.tar.gz file found"; exit 0; else echo "/results/gatling/update-1500/update-1500.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/update-1500/update-1500.tar.gz file found" 2025-03-28 16:00:09,896 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:00:10,813 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:00:10,813 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:10,813 DEBUG --- stdout --- 2025-03-28 16:00:10,813 DEBUG /results/gatling/update-1500/update-1500.tar.gz file found 2025-03-28 16:00:10,813 DEBUG --- stderr --- 2025-03-28 16:00:10,813 DEBUG 2025-03-28 16:00:10,813 INFO 2025-03-28 16:00:10,814 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/gatling/update-1500/update-1500.tar.gz 2025-03-28 16:00:11,644 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:00:11,644 DEBUG --- stdout --- 2025-03-28 16:00:11,644 DEBUG 308 /results/gatling/update-1500/update-1500.tar.gz 2025-03-28 16:00:11,644 DEBUG --- stderr --- 2025-03-28 16:00:11,644 DEBUG 2025-03-28 16:00:11,644 INFO - Download file (size 308k) source : /results/gatling/update-1500/update-1500.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-1500.tar.gz 2025-03-28 16:00:11,645 INFO 2025-03-28 16:00:11,645 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/update-1500/update-1500.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-1500.tar.gz 2025-03-28 16:00:11,645 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:00:12,638 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:12,638 DEBUG --- stdout --- 2025-03-28 16:00:12,638 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:00:12,638 DEBUG --- stderr --- 2025-03-28 16:00:12,638 DEBUG ________________________________________________________________________________ [2025-03-28 16:00:12] Reporting-update-1500 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 16:00:12,639 INFO 2025-03-28 16:00:12,639 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-1500.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/03_update-1500/gatling 2025-03-28 16:00:12,653 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:00:12,653 DEBUG --- stdout --- 2025-03-28 16:00:12,654 DEBUG 2025-03-28 16:00:12,654 DEBUG --- stderr --- 2025-03-28 16:00:12,654 DEBUG 2025-03-28 16:00:12,654 INFO ________________________________________________________________________________ [2025-03-28 16:00:12] Reporting-update-1500 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 16:00:12,654 INFO 2025-03-28 16:00:12,654 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:00:12,654 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:00:12,932 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:00:12,933 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:12,933 DEBUG --- stdout --- 2025-03-28 16:00:12,933 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:00:12,933 DEBUG --- stderr --- 2025-03-28 16:00:12,933 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:00:12,934 INFO 2025-03-28 16:00:12,934 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/Reporting-update-1500/rc.txt" ]; then echo "/results/orders/Reporting-update-1500/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-update-1500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-1500/rc.txt file found" 2025-03-28 16:00:12,934 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:00:13,751 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:00:13,751 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:13,751 DEBUG --- stdout --- 2025-03-28 16:00:13,751 DEBUG /results/orders/Reporting-update-1500/rc.txt file found 2025-03-28 16:00:13,751 DEBUG --- stderr --- 2025-03-28 16:00:13,751 DEBUG 2025-03-28 16:00:13,751 INFO 2025-03-28 16:00:13,751 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/Reporting-update-1500/rc.txt 2025-03-28 16:00:14,564 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:00:14,564 DEBUG --- stdout --- 2025-03-28 16:00:14,564 DEBUG 4 /results/orders/Reporting-update-1500/rc.txt 2025-03-28 16:00:14,564 DEBUG --- stderr --- 2025-03-28 16:00:14,564 DEBUG 2025-03-28 16:00:14,564 INFO - Download file (size 4k) source : /results/orders/Reporting-update-1500/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-update-1500/rc.txt 2025-03-28 16:00:14,565 INFO 2025-03-28 16:00:14,565 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-update-1500/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-update-1500/rc.txt 2025-03-28 16:00:14,565 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:00:15,295 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:15,295 DEBUG --- stdout --- 2025-03-28 16:00:15,295 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:00:15,295 DEBUG --- stderr --- 2025-03-28 16:00:15,295 DEBUG 2025-03-28 16:00:15,295 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-update-1500/rc.txt is small (1 lines), printing all lines: 2025-03-28 16:00:15,295 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 16:00:15] Reporting-update-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:00:15] Reporting-update-1500 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 16:00:15,627 INFO 2025-03-28 16:00:15,627 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:00:15,627 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:00:15,903 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:00:15,903 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:15,903 DEBUG --- stdout --- 2025-03-28 16:00:15,903 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:00:15,903 DEBUG --- stderr --- 2025-03-28 16:00:15,904 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:00:15,904 INFO 2025-03-28 16:00:15,905 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/Reporting-update-1500/stdout.txt" ]; then echo "/results/orders/Reporting-update-1500/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-update-1500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-1500/stdout.txt file found" 2025-03-28 16:00:15,905 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:00:16,704 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:00:16,704 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:16,704 DEBUG --- stdout --- 2025-03-28 16:00:16,704 DEBUG /results/orders/Reporting-update-1500/stdout.txt file found 2025-03-28 16:00:16,704 DEBUG --- stderr --- 2025-03-28 16:00:16,704 DEBUG 2025-03-28 16:00:16,704 INFO 2025-03-28 16:00:16,705 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/Reporting-update-1500/stdout.txt 2025-03-28 16:00:17,502 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:00:17,502 DEBUG --- stdout --- 2025-03-28 16:00:17,502 DEBUG 8 /results/orders/Reporting-update-1500/stdout.txt 2025-03-28 16:00:17,502 DEBUG --- stderr --- 2025-03-28 16:00:17,502 DEBUG 2025-03-28 16:00:17,502 INFO - Download file (size 8k) source : /results/orders/Reporting-update-1500/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-update-1500/stdout.txt 2025-03-28 16:00:17,503 INFO 2025-03-28 16:00:17,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-update-1500/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-update-1500/stdout.txt 2025-03-28 16:00:17,503 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:00:18,231 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:18,231 DEBUG --- stdout --- 2025-03-28 16:00:18,231 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:00:18,231 DEBUG --- stderr --- 2025-03-28 16:00:18,231 DEBUG 2025-03-28 16:00:18,231 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-update-1500/stdout.txt is small (92 lines), printing all lines: 2025-03-28 16:00:18,231 INFO ----- output ----- 2025-03-28 15:56:15,143 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp8obsngzz 2025-03-28 15:56:15,144 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/update-1500/loadtest-20250328074425930" -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/tmp8obsngzz/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/tmp8obsngzz/src/test/resources [INFO] [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- [INFO] No sources to compile [INFO] [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- [INFO] compile in 0.0 s [INFO] No sources to compile [INFO] [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< [INFO] [INFO] [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 15:56:16,951 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 15:56:16,953 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 15:56:16,953 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 15:56:16,954 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 15:56:16,959 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 15:56:16,959 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 15:56:16,967 |-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 15:56:16,967 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 15:56:16,967 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 15:56:16,968 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 15:56:16,971 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/home/jenkins/.m2/repository/io/gatling/gatling-maven-plugin/4.9.6/gatling-maven-plugin-4.9.6.jar!/logback.xml] 15:56:16,975 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@240237d2 - URL [jar:file:/home/jenkins/.m2/repository/io/gatling/gatling-maven-plugin/4.9.6/gatling-maven-plugin-4.9.6.jar!/logback.xml] is not of type file 15:56:17,018 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 15:56:17,018 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 15:56:17,058 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 15:56:17,058 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 15:56:17,065 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 15:56:17,086 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 15:56:17,086 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 15:56:17,087 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 15:56:17,087 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 15:56:17,087 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 120 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY Parsing log file(s)... Parsing log file(s) done in 229s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 5377308 (OK=5376967 KO=341 ) > min response time 38 (OK=59 KO=38 ) > max response time 26315 (OK=26315 KO=17111 ) > mean response time 122 (OK=121 KO=4197 ) > std deviation 235 (OK=232 KO=2205 ) > response time 50th percentile 101 (OK=101 KO=4401 ) > response time 75th percentile 114 (OK=114 KO=5737 ) > response time 95th percentile 160 (OK=159 KO=6511 ) > response time 99th percentile 346 (OK=345 KO=10026 ) > mean requests/sec 1493.7 (OK=1493.6 KO=0.09 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 5359965 ( 99.68%) > 800 ms <= t < 1200 ms 762 ( 0.01%) > t >= 1200 ms 16240 ( 0.3%) > failed 341 ( 0.01%) ---- Errors -------------------------------------------------------------------- > status.find.in(200), but actually found 502 332 (97.36%) > j.i.IOException: Premature close 6 ( 1.76%) > i.n.c.ConnectTimeoutException: connection timed out after 1000 3 ( 0.88%) 0 ms: openam-perf-200m.forgeblocks.com/34.107.203.37:443 ================================================================================ Reports generated, please open the following file: file:///results/gatling/update-1500/loadtest-20250328074425930/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 03:51 min [INFO] Finished at: 2025-03-28T16:00:07Z [INFO] ------------------------------------------------------------------------ 2025-03-28 16:00:07,679 INFO Gatling simulation run ended successfully 2025-03-28 16:00:07,679 INFO Creating archive update-1500.tar.gz with report files 2025-03-28 16:00:07,791 INFO Archive /results/gatling/update-1500/update-1500.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 16:00:18] Reporting-update-1500 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 16:00:18] Reporting-update-1500 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 16:00:18,232 INFO 2025-03-28 16:00:18,232 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:00:18,232 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:00:18,510 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:00:18,510 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:18,511 DEBUG --- stdout --- 2025-03-28 16:00:18,511 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:00:18,511 DEBUG --- stderr --- 2025-03-28 16:00:18,511 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:00:18,512 INFO 2025-03-28 16:00:18,512 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/Reporting-update-1500/stderr.txt" ]; then echo "/results/orders/Reporting-update-1500/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-update-1500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-1500/stderr.txt file found" 2025-03-28 16:00:18,512 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:00:19,312 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:00:19,312 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:19,312 DEBUG --- stdout --- 2025-03-28 16:00:19,312 DEBUG /results/orders/Reporting-update-1500/stderr.txt file found 2025-03-28 16:00:19,312 DEBUG --- stderr --- 2025-03-28 16:00:19,312 DEBUG 2025-03-28 16:00:19,312 INFO 2025-03-28 16:00:19,312 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/Reporting-update-1500/stderr.txt 2025-03-28 16:00:20,116 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:00:20,116 DEBUG --- stdout --- 2025-03-28 16:00:20,116 DEBUG 0 /results/orders/Reporting-update-1500/stderr.txt 2025-03-28 16:00:20,116 DEBUG --- stderr --- 2025-03-28 16:00:20,116 DEBUG 2025-03-28 16:00:20,116 INFO - Download file (size 0k) source : /results/orders/Reporting-update-1500/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-update-1500/stderr.txt 2025-03-28 16:00:20,117 INFO 2025-03-28 16:00:20,117 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-update-1500/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-update-1500/stderr.txt 2025-03-28 16:00:20,117 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:00:20,903 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:20,904 DEBUG --- stdout --- 2025-03-28 16:00:20,904 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:00:20,904 DEBUG --- stderr --- 2025-03-28 16:00:20,904 DEBUG 2025-03-28 16:00:20,904 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/14_Reporting-update-1500/stderr.txt is small (0 lines), printing all lines: 2025-03-28 16:00:20,904 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 16:00:20] Reporting-update-1500 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 16:00:20] Reporting-update-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 UpdateManagedUser [warn] responseTimePercentiles was not found in /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/03_update-1500/gatling/req_updatemanagedus--1469575327.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 16:00:20] Reporting-update-1500 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 5377308, "ok": 5376967, "ko": 341 }, "minResponseTime": { "total": 38, "ok": 59, "ko": 38 }, "maxResponseTime": { "total": 26315, "ok": 26315, "ko": 17111 }, "meanResponseTime": { "total": 122, "ok": 121, "ko": 4197 }, "standardDeviation": { "total": 235, "ok": 232, "ko": 2205 }, "percentiles1": { "total": 101, "ok": 101, "ko": 4401 }, "percentiles2": { "total": 114, "ok": 114, "ko": 5737 }, "percentiles3": { "total": 160, "ok": 159, "ko": 6511 }, "percentiles4": { "total": 346, "ok": 345, "ko": 10026 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 5359965, "percentage": 99.67747802431998 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 762, "percentage": 0.014170659370822724 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 16240, "percentage": 0.3020098532574292 }, "group4": { "name": "failed", "htmlName": "failed", "count": 341, "percentage": 0.006341463051772374 }, "meanNumberOfRequestsPerSecond": { "total": 1493.6966666666667, "ok": 1493.6019444444444, "ko": 0.09472222222222222 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 59, "ok": 59, "ko": 0 }, "maxResponseTime": { "total": 68, "ok": 68, "ko": 0 }, "meanResponseTime": { "total": 64, "ok": 64, "ko": 0 }, "standardDeviation": { "total": 5, "ok": 5, "ko": 0 }, "percentiles1": { "total": 64, "ok": 64, "ko": 0 }, "percentiles2": { "total": 66, "ok": 66, "ko": 0 }, "percentiles3": { "total": 68, "ok": 68, "ko": 0 }, "percentiles4": { "total": 68, "ok": 68, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 85, "ok": 85, "ko": 0 }, "maxResponseTime": { "total": 136, "ok": 136, "ko": 0 }, "meanResponseTime": { "total": 111, "ok": 111, "ko": 0 }, "standardDeviation": { "total": 26, "ok": 26, "ko": 0 }, "percentiles1": { "total": 111, "ok": 111, "ko": 0 }, "percentiles2": { "total": 123, "ok": 123, "ko": 0 }, "percentiles3": { "total": 133, "ok": 133, "ko": 0 }, "percentiles4": { "total": 135, "ok": 135, "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": 73, "ok": 73, "ko": 0 }, "maxResponseTime": { "total": 90, "ok": 90, "ko": 0 }, "meanResponseTime": { "total": 82, "ok": 82, "ko": 0 }, "standardDeviation": { "total": 9, "ok": 9, "ko": 0 }, "percentiles1": { "total": 82, "ok": 82, "ko": 0 }, "percentiles2": { "total": 86, "ok": 86, "ko": 0 }, "percentiles3": { "total": 89, "ok": 89, "ko": 0 }, "percentiles4": { "total": 90, "ok": 90, "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": 60, "ok": 60, "ko": 0 }, "maxResponseTime": { "total": 60, "ok": 60, "ko": 0 }, "meanResponseTime": { "total": 60, "ok": 60, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 60, "ok": 60, "ko": 0 }, "percentiles2": { "total": 60, "ok": 60, "ko": 0 }, "percentiles3": { "total": 60, "ok": 60, "ko": 0 }, "percentiles4": { "total": 60, "ok": 60, "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": 60, "ok": 60, "ko": 0 }, "maxResponseTime": { "total": 69, "ok": 69, "ko": 0 }, "meanResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "standardDeviation": { "total": 5, "ok": 5, "ko": 0 }, "percentiles1": { "total": 65, "ok": 65, "ko": 0 }, "percentiles2": { "total": 67, "ok": 67, "ko": 0 }, "percentiles3": { "total": 69, "ok": 69, "ko": 0 }, "percentiles4": { "total": 69, "ok": 69, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "UpdateManagedUser", "numberOfRequests": { "total": 5377298, "ok": 5376957, "ko": 341 }, "minResponseTime": { "total": 38, "ok": 65, "ko": 38 }, "maxResponseTime": { "total": 26315, "ok": 26315, "ko": 17111 }, "meanResponseTime": { "total": 122, "ok": 121, "ko": 4197 }, "standardDeviation": { "total": 235, "ok": 232, "ko": 2205 }, "percentiles1": { "total": 101, "ok": 101, "ko": 4401 }, "percentiles2": { "total": 114, "ok": 114, "ko": 5737 }, "percentiles3": { "total": 159, "ok": 159, "ko": 6511 }, "percentiles4": { "total": 346, "ok": 345, "ko": 10026 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 5359955, "percentage": 99.67747742453552 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 762, "percentage": 0.014170685723573438 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 16240, "percentage": 0.30201041489610586 }, "group4": { "name": "failed", "htmlName": "failed", "count": 341, "percentage": 0.006341474844801236 }, "meanNumberOfRequestsPerSecond": { "total": 1493.693888888889, "ok": 1493.5991666666666, "ko": 0.09472222222222222 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 1494, "avg_response_time": 122, "max_response_time": 26315, "min_response_time": 59, "num_requests": 5377308, "num_requests_pass": 5376967, "num_requests_percent_pass": 99.994 }, "options": "; testMode:open; testCase:idm.ManagedUsersUpdate; 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": 68, "total": 68 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 64, "total": 64 }, "minResponseTime": { "ko": 0, "ok": 59, "total": 59 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 64, "total": 64 }, "percentiles2": { "ko": 0, "ok": 66, "total": 66 }, "percentiles3": { "ko": 0, "ok": 68, "total": 68 }, "percentiles4": { "ko": 0, "ok": 68, "total": 68 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 5, "total": 5 } }, { "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": 136, "total": 136 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 111, "total": 111 }, "minResponseTime": { "ko": 0, "ok": 85, "total": 85 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 111, "total": 111 }, "percentiles2": { "ko": 0, "ok": 123, "total": 123 }, "percentiles3": { "ko": 0, "ok": 133, "total": 133 }, "percentiles4": { "ko": 0, "ok": 135, "total": 135 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 26, "total": 26 } }, { "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": 90, "total": 90 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 82, "total": 82 }, "minResponseTime": { "ko": 0, "ok": 73, "total": 73 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 82, "total": 82 }, "percentiles2": { "ko": 0, "ok": 86, "total": 86 }, "percentiles3": { "ko": 0, "ok": 89, "total": 89 }, "percentiles4": { "ko": 0, "ok": 90, "total": 90 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 9, "total": 9 } }, { "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": 60, "total": 60 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 60, "total": 60 }, "minResponseTime": { "ko": 0, "ok": 60, "total": 60 }, "name": "User Authorize PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 60, "total": 60 }, "percentiles2": { "ko": 0, "ok": 60, "total": 60 }, "percentiles3": { "ko": 0, "ok": 60, "total": 60 }, "percentiles4": { "ko": 0, "ok": 60, "total": 60 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 2, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 69, "total": 69 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 65, "total": 65 }, "minResponseTime": { "ko": 0, "ok": 60, "total": 60 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 65, "total": 65 }, "percentiles2": { "ko": 0, "ok": 67, "total": 67 }, "percentiles3": { "ko": 0, "ok": 69, "total": 69 }, "percentiles4": { "ko": 0, "ok": 69, "total": 69 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 5, "total": 5 } }, { "group1": { "count": 5359955, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.67747742453552 }, "group2": { "count": 762, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.014170685723573438 }, "group3": { "count": 16240, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.30201041489610586 }, "group4": { "count": 341, "htmlName": "failed", "name": "failed", "percentage": 0.006341474844801236 }, "maxResponseTime": { "ko": 17111, "ok": 26315, "total": 26315 }, "meanNumberOfRequestsPerSecond": { "ko": 0.09472222222222222, "ok": 1493.5991666666666, "total": 1493.693888888889 }, "meanResponseTime": { "ko": 4197, "ok": 121, "total": 122 }, "minResponseTime": { "ko": 38, "ok": 65, "total": 38 }, "name": "UpdateManagedUser", "numberOfRequests": { "ko": 341, "ok": 5376957, "total": 5377298 }, "percentiles1": { "ko": 4401, "ok": 101, "total": 101 }, "percentiles2": { "ko": 5737, "ok": 114, "total": 114 }, "percentiles3": { "ko": 6511, "ok": 159, "total": 159 }, "percentiles4": { "ko": 10026, "ok": 345, "total": 346 }, "spikes": [], "standardDeviation": { "ko": 2205, "ok": 232, "total": 235 } } ], "global": { "group1": { "count": 5359965, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.67747802431998 }, "group2": { "count": 762, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.014170659370822724 }, "group3": { "count": 16240, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.3020098532574292 }, "group4": { "count": 341, "htmlName": "failed", "name": "failed", "percentage": 0.006341463051772374 }, "maxResponseTime": { "ko": 17111, "ok": 26315, "total": 26315 }, "meanNumberOfRequestsPerSecond": { "ko": 0.09472222222222222, "ok": 1493.6019444444444, "total": 1493.6966666666667 }, "meanResponseTime": { "ko": 4197, "ok": 121, "total": 122 }, "minResponseTime": { "ko": 38, "ok": 59, "total": 38 }, "name": "All Requests", "numberOfRequests": { "ko": 341, "ok": 5376967, "total": 5377308 }, "percentiles1": { "ko": 4401, "ok": 101, "total": 101 }, "percentiles2": { "ko": 5737, "ok": 114, "total": 114 }, "percentiles3": { "ko": 6511, "ok": 159, "total": 160 }, "percentiles4": { "ko": 10026, "ok": 345, "total": 346 }, "standardDeviation": { "ko": 2205, "ok": 232, "total": 235 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 16:00:20] Reporting-update-1500 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 16:00:20] Reporting-update-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/14_Reporting-update-1500/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 16:00:20] Reporting-update-1500 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped