--Task-- name: Reporting-patch-1000 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'] preceding_task: None options: {'task_name': 'patch-1000'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 16:10:55 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 16:10:55 - 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-1000/stdout.txt Pod stderr file : /results/orders/Reporting-patch-1000/stderr.txt Pod log : /results/orders/Reporting-patch-1000 ________________________________________________________________________________ [2025-03-28 16:10:55] Reporting-patch-1000 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/18_Reporting-patch-1000/order.json { "name": "Reporting-patch-1000", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/patch-1000\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"patch-1000.tar.gz\"", "out-dir": "/results/orders/Reporting-patch-1000" } ________________________________________________________________________________ [2025-03-28 16:10:55] Reporting-patch-1000 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 16:10:55,678 INFO 2025-03-28 16:10:55,678 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:10:55,678 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 16:10:55,893 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:10:55,893 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:55,893 DEBUG --- stdout --- 2025-03-28 16:10:55,893 DEBUG WAITING 2025-03-28 16:10:55,893 DEBUG --- stderr --- 2025-03-28 16:10:55,893 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:10:55] Reporting-patch-1000 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 16:10:55,893 INFO 2025-03-28 16:10:55,893 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2025-03-28 16:10:55,893 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:10:56,172 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:10:56,173 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:56,173 DEBUG --- stdout --- 2025-03-28 16:10:56,173 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:10:56,173 DEBUG --- stderr --- 2025-03-28 16:10:56,173 DEBUG 2025-03-28 16:10:56,173 INFO 2025-03-28 16:10:56,173 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/18_Reporting-patch-1000/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 16:10:56,173 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:10:57,542 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:10:57,542 DEBUG --- stdout --- 2025-03-28 16:10:57,542 DEBUG 2025-03-28 16:10:57,542 DEBUG --- stderr --- 2025-03-28 16:10:57,542 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 16:10:57] Reporting-patch-1000 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 16:11:02,543 INFO 2025-03-28 16:11:02,543 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:11:02,543 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 16:11:02,758 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:11:07,973 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:11:13,189 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:11:18,403 INFO [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:11:23,620 INFO [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:11:28,835 INFO [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:11:34,049 INFO [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:11:39,264 INFO [loop_until]: Function succeeded after 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:11:44,479 INFO [loop_until]: Function succeeded after 41s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:11:49,694 INFO [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:11:55,002 INFO [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:00,217 INFO [loop_until]: Function succeeded after 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:05,431 INFO [loop_until]: Function succeeded after 1m 02s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:10,650 INFO [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:15,865 INFO [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:21,080 INFO [loop_until]: Function succeeded after 1m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:26,367 INFO [loop_until]: Function succeeded after 1m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:31,582 INFO [loop_until]: Function succeeded after 1m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:36,797 INFO [loop_until]: Function succeeded after 1m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:42,012 INFO [loop_until]: Function succeeded after 1m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:47,319 INFO [loop_until]: Function succeeded after 1m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:52,533 INFO [loop_until]: Function succeeded after 1m 49s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:12:57,792 INFO [loop_until]: Function succeeded after 1m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:13:03,007 INFO [loop_until]: Function succeeded after 2m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:13:08,222 INFO [loop_until]: Function succeeded after 2m 05s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:13:13,437 INFO [loop_until]: Function succeeded after 2m 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:13:18,652 INFO [loop_until]: Function succeeded after 2m 16s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:13:23,867 INFO [loop_until]: Function succeeded after 2m 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:13:29,146 INFO [loop_until]: Function succeeded after 2m 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:13:34,361 INFO [loop_until]: Function succeeded after 2m 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:13:39,575 INFO [loop_until]: Function succeeded after 2m 37s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:13:44,790 INFO [loop_until]: Function succeeded after 2m 42s (rc=0) - expected pattern found 2025-03-28 16:13:44,791 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:44,791 DEBUG --- stdout --- 2025-03-28 16:13:44,791 DEBUG WAITING 2025-03-28 16:13:44,791 DEBUG --- stderr --- 2025-03-28 16:13:44,791 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:44] Reporting-patch-1000 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 16:13:44,792 INFO 2025-03-28 16:13:44,792 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:44,792 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:13:45,089 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:13:45,089 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:45,089 DEBUG --- stdout --- 2025-03-28 16:13:45,089 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:13:45,089 DEBUG --- stderr --- 2025-03-28 16:13:45,089 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:13:45,090 INFO 2025-03-28 16:13:45,090 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-1000/patch-1000.tar.gz" ]; then echo "/results/gatling/patch-1000/patch-1000.tar.gz file found"; exit 0; else echo "/results/gatling/patch-1000/patch-1000.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/patch-1000/patch-1000.tar.gz file found" 2025-03-28 16:13:45,090 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:13:45,952 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:13:45,952 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:45,952 DEBUG --- stdout --- 2025-03-28 16:13:45,952 DEBUG /results/gatling/patch-1000/patch-1000.tar.gz file found 2025-03-28 16:13:45,952 DEBUG --- stderr --- 2025-03-28 16:13:45,952 DEBUG 2025-03-28 16:13:45,952 INFO 2025-03-28 16:13:45,952 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-1000/patch-1000.tar.gz 2025-03-28 16:13:46,758 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:13:46,758 DEBUG --- stdout --- 2025-03-28 16:13:46,758 DEBUG 300 /results/gatling/patch-1000/patch-1000.tar.gz 2025-03-28 16:13:46,758 DEBUG --- stderr --- 2025-03-28 16:13:46,758 DEBUG 2025-03-28 16:13:46,758 INFO - Download file (size 300k) source : /results/gatling/patch-1000/patch-1000.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1000.tar.gz 2025-03-28 16:13:46,759 INFO 2025-03-28 16:13:46,759 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/patch-1000/patch-1000.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1000.tar.gz 2025-03-28 16:13:46,759 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:13:47,752 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:47,752 DEBUG --- stdout --- 2025-03-28 16:13:47,752 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:13:47,752 DEBUG --- stderr --- 2025-03-28 16:13:47,752 DEBUG ________________________________________________________________________________ [2025-03-28 16:13:47] Reporting-patch-1000 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 16:13:47,752 INFO 2025-03-28 16:13:47,752 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1000.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/07_patch-1000/gatling 2025-03-28 16:13:47,767 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:13:47,767 DEBUG --- stdout --- 2025-03-28 16:13:47,767 DEBUG 2025-03-28 16:13:47,767 DEBUG --- stderr --- 2025-03-28 16:13:47,767 DEBUG 2025-03-28 16:13:47,767 INFO ________________________________________________________________________________ [2025-03-28 16:13:47] Reporting-patch-1000 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 16:13:47,767 INFO 2025-03-28 16:13:47,767 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:47,767 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:13:48,044 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:13:48,045 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:48,045 DEBUG --- stdout --- 2025-03-28 16:13:48,045 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:13:48,045 DEBUG --- stderr --- 2025-03-28 16:13:48,045 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:13:48,046 INFO 2025-03-28 16:13:48,046 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-1000/rc.txt" ]; then echo "/results/orders/Reporting-patch-1000/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1000/rc.txt file found" 2025-03-28 16:13:48,046 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:13:48,848 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:13:48,848 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:48,848 DEBUG --- stdout --- 2025-03-28 16:13:48,848 DEBUG /results/orders/Reporting-patch-1000/rc.txt file found 2025-03-28 16:13:48,848 DEBUG --- stderr --- 2025-03-28 16:13:48,848 DEBUG 2025-03-28 16:13:48,848 INFO 2025-03-28 16:13:48,849 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-1000/rc.txt 2025-03-28 16:13:49,659 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:13:49,659 DEBUG --- stdout --- 2025-03-28 16:13:49,659 DEBUG 4 /results/orders/Reporting-patch-1000/rc.txt 2025-03-28 16:13:49,659 DEBUG --- stderr --- 2025-03-28 16:13:49,659 DEBUG 2025-03-28 16:13:49,659 INFO - Download file (size 4k) source : /results/orders/Reporting-patch-1000/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/18_Reporting-patch-1000/rc.txt 2025-03-28 16:13:49,660 INFO 2025-03-28 16:13:49,660 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-1000/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/18_Reporting-patch-1000/rc.txt 2025-03-28 16:13:49,660 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:13:50,392 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:50,392 DEBUG --- stdout --- 2025-03-28 16:13:50,392 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:13:50,392 DEBUG --- stderr --- 2025-03-28 16:13:50,392 DEBUG 2025-03-28 16:13:50,392 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/18_Reporting-patch-1000/rc.txt is small (1 lines), printing all lines: 2025-03-28 16:13:50,392 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 16:13:50] Reporting-patch-1000 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:13:50] Reporting-patch-1000 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 16:13:50,630 INFO 2025-03-28 16:13:50,631 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:50,631 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:13:50,908 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:13:50,908 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:50,908 DEBUG --- stdout --- 2025-03-28 16:13:50,908 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:13:50,908 DEBUG --- stderr --- 2025-03-28 16:13:50,908 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:13:50,909 INFO 2025-03-28 16:13:50,909 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-1000/stdout.txt" ]; then echo "/results/orders/Reporting-patch-1000/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1000/stdout.txt file found" 2025-03-28 16:13:50,909 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:13:51,718 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:13:51,718 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:51,718 DEBUG --- stdout --- 2025-03-28 16:13:51,718 DEBUG /results/orders/Reporting-patch-1000/stdout.txt file found 2025-03-28 16:13:51,718 DEBUG --- stderr --- 2025-03-28 16:13:51,718 DEBUG 2025-03-28 16:13:51,718 INFO 2025-03-28 16:13:51,718 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-1000/stdout.txt 2025-03-28 16:13:52,515 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:13:52,515 DEBUG --- stdout --- 2025-03-28 16:13:52,515 DEBUG 8 /results/orders/Reporting-patch-1000/stdout.txt 2025-03-28 16:13:52,515 DEBUG --- stderr --- 2025-03-28 16:13:52,515 DEBUG 2025-03-28 16:13:52,515 INFO - Download file (size 8k) source : /results/orders/Reporting-patch-1000/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/18_Reporting-patch-1000/stdout.txt 2025-03-28 16:13:52,516 INFO 2025-03-28 16:13:52,516 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-1000/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/18_Reporting-patch-1000/stdout.txt 2025-03-28 16:13:52,516 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:13:53,239 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:53,239 DEBUG --- stdout --- 2025-03-28 16:13:53,239 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:13:53,239 DEBUG --- stderr --- 2025-03-28 16:13:53,239 DEBUG 2025-03-28 16:13:53,239 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/18_Reporting-patch-1000/stdout.txt is small (91 lines), printing all lines: 2025-03-28 16:13:53,239 INFO ----- output ----- 2025-03-28 16:10:58,331 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpw3u92sc_ 2025-03-28 16:10:58,333 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/patch-1000/loadtest-20250328114715417" -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/tmpw3u92sc_/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/tmpw3u92sc_/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:11:00,144 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 16:11:00,145 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 16:11:00,145 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:11:00,146 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 16:11:00,152 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 16:11:00,152 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 16:11:00,159 |-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:11:00,159 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 16:11:00,160 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 16:11:00,160 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 16:11:00,163 |-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:11:00,167 |-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:11:00,213 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 16:11:00,213 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 16:11:00,253 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 16:11:00,253 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 16:11:00,260 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 16:11:00,280 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 16:11:00,281 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 16:11:00,281 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 16:11:00,281 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 16:11:00,281 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 121 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY Parsing log file(s)... Parsing log file(s) done in 158s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 3588419 (OK=3588405 KO=14 ) > min response time 37 (OK=59 KO=37 ) > max response time 10001 (OK=5734 KO=10001 ) > mean response time 85 (OK=85 KO=752 ) > std deviation 18 (OK=18 KO=2565 ) > response time 50th percentile 80 (OK=80 KO=41 ) > response time 75th percentile 92 (OK=92 KO=41 ) > response time 95th percentile 106 (OK=106 KO=3528 ) > response time 99th percentile 157 (OK=157 KO=8706 ) > mean requests/sec 996.78 (OK=996.78 KO=0 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 3588306 ( 100%) > 800 ms <= t < 1200 ms 89 ( 0%) > t >= 1200 ms 10 ( 0%) > failed 14 ( 0%) ---- Errors -------------------------------------------------------------------- > status.find.in(200), but actually found 502 13 (92.86%) > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 1 ( 7.14%) r 10000ms ================================================================================ Reports generated, please open the following file: file:///results/gatling/patch-1000/loadtest-20250328114715417/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 02:40 min [INFO] Finished at: 2025-03-28T16:13:39Z [INFO] ------------------------------------------------------------------------ 2025-03-28 16:13:39,741 INFO Gatling simulation run ended successfully 2025-03-28 16:13:39,741 INFO Creating archive patch-1000.tar.gz with report files 2025-03-28 16:13:39,843 INFO Archive /results/gatling/patch-1000/patch-1000.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 16:13:53] Reporting-patch-1000 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 16:13:53] Reporting-patch-1000 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 16:13:53,240 INFO 2025-03-28 16:13:53,240 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:53,240 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:13:53,518 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:13:53,518 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:53,518 DEBUG --- stdout --- 2025-03-28 16:13:53,518 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:13:53,518 DEBUG --- stderr --- 2025-03-28 16:13:53,518 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:13:53,519 INFO 2025-03-28 16:13:53,519 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-1000/stderr.txt" ]; then echo "/results/orders/Reporting-patch-1000/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1000/stderr.txt file found" 2025-03-28 16:13:53,519 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:13:54,321 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:13:54,322 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:54,322 DEBUG --- stdout --- 2025-03-28 16:13:54,322 DEBUG /results/orders/Reporting-patch-1000/stderr.txt file found 2025-03-28 16:13:54,322 DEBUG --- stderr --- 2025-03-28 16:13:54,322 DEBUG 2025-03-28 16:13:54,322 INFO 2025-03-28 16:13:54,322 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-1000/stderr.txt 2025-03-28 16:13:55,116 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:13:55,116 DEBUG --- stdout --- 2025-03-28 16:13:55,116 DEBUG 0 /results/orders/Reporting-patch-1000/stderr.txt 2025-03-28 16:13:55,116 DEBUG --- stderr --- 2025-03-28 16:13:55,116 DEBUG 2025-03-28 16:13:55,116 INFO - Download file (size 0k) source : /results/orders/Reporting-patch-1000/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/18_Reporting-patch-1000/stderr.txt 2025-03-28 16:13:55,117 INFO 2025-03-28 16:13:55,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-patch-1000/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/18_Reporting-patch-1000/stderr.txt 2025-03-28 16:13:55,117 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:13:56,019 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:13:56,019 DEBUG --- stdout --- 2025-03-28 16:13:56,019 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:13:56,019 DEBUG --- stderr --- 2025-03-28 16:13:56,019 DEBUG 2025-03-28 16:13:56,019 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/18_Reporting-patch-1000/stderr.txt is small (0 lines), printing all lines: 2025-03-28 16:13:56,019 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 16:13:56] Reporting-patch-1000 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 16:13:56] Reporting-patch-1000 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/07_patch-1000/gatling/req_patchmanageduse--1542793534.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 16:13:56] Reporting-patch-1000 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 3588419, "ok": 3588405, "ko": 14 }, "minResponseTime": { "total": 37, "ok": 59, "ko": 37 }, "maxResponseTime": { "total": 10001, "ok": 5734, "ko": 10001 }, "meanResponseTime": { "total": 85, "ok": 85, "ko": 752 }, "standardDeviation": { "total": 18, "ok": 18, "ko": 2565 }, "percentiles1": { "total": 80, "ok": 80, "ko": 41 }, "percentiles2": { "total": 92, "ok": 92, "ko": 41 }, "percentiles3": { "total": 106, "ok": 106, "ko": 3528 }, "percentiles4": { "total": 157, "ok": 157, "ko": 8706 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 3588306, "percentage": 99.99685098089158 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 89, "percentage": 0.002480200890698661 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 10, "percentage": 0.0002786742573818721 }, "group4": { "name": "failed", "htmlName": "failed", "count": 14, "percentage": 0.0003901439603346209 }, "meanNumberOfRequestsPerSecond": { "total": 996.7830555555555, "ok": 996.7791666666667, "ko": 0.0038888888888888888 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 60, "ok": 60, "ko": 0 }, "maxResponseTime": { "total": 76, "ok": 76, "ko": 0 }, "meanResponseTime": { "total": 68, "ok": 68, "ko": 0 }, "standardDeviation": { "total": 8, "ok": 8, "ko": 0 }, "percentiles1": { "total": 68, "ok": 68, "ko": 0 }, "percentiles2": { "total": 72, "ok": 72, "ko": 0 }, "percentiles3": { "total": 75, "ok": 75, "ko": 0 }, "percentiles4": { "total": 76, "ok": 76, "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": 104, "ok": 104, "ko": 0 }, "maxResponseTime": { "total": 119, "ok": 119, "ko": 0 }, "meanResponseTime": { "total": 112, "ok": 112, "ko": 0 }, "standardDeviation": { "total": 8, "ok": 8, "ko": 0 }, "percentiles1": { "total": 112, "ok": 112, "ko": 0 }, "percentiles2": { "total": 115, "ok": 115, "ko": 0 }, "percentiles3": { "total": 118, "ok": 118, "ko": 0 }, "percentiles4": { "total": 119, "ok": 119, "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": 71, "ok": 71, "ko": 0 }, "maxResponseTime": { "total": 76, "ok": 76, "ko": 0 }, "meanResponseTime": { "total": 74, "ok": 74, "ko": 0 }, "standardDeviation": { "total": 3, "ok": 3, "ko": 0 }, "percentiles1": { "total": 74, "ok": 74, "ko": 0 }, "percentiles2": { "total": 75, "ok": 75, "ko": 0 }, "percentiles3": { "total": 76, "ok": 76, "ko": 0 }, "percentiles4": { "total": 76, "ok": 76, "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": 59, "ok": 59, "ko": 0 }, "maxResponseTime": { "total": 94, "ok": 94, "ko": 0 }, "meanResponseTime": { "total": 77, "ok": 77, "ko": 0 }, "standardDeviation": { "total": 18, "ok": 18, "ko": 0 }, "percentiles1": { "total": 77, "ok": 77, "ko": 0 }, "percentiles2": { "total": 85, "ok": 85, "ko": 0 }, "percentiles3": { "total": 92, "ok": 92, "ko": 0 }, "percentiles4": { "total": 94, "ok": 94, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "User AccessToken PKCE stage", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 59, "ok": 59, "ko": 0 }, "maxResponseTime": { "total": 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": "PatchManagedUser", "numberOfRequests": { "total": 3588409, "ok": 3588395, "ko": 14 }, "minResponseTime": { "total": 37, "ok": 64, "ko": 37 }, "maxResponseTime": { "total": 10001, "ok": 5734, "ko": 10001 }, "meanResponseTime": { "total": 85, "ok": 85, "ko": 752 }, "standardDeviation": { "total": 18, "ok": 18, "ko": 2565 }, "percentiles1": { "total": 80, "ok": 80, "ko": 41 }, "percentiles2": { "total": 92, "ok": 92, "ko": 41 }, "percentiles3": { "total": 106, "ok": 106, "ko": 3528 }, "percentiles4": { "total": 157, "ok": 157, "ko": 8706 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 3588296, "percentage": 99.99685097211606 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 89, "percentage": 0.0024802078023993365 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 10, "percentage": 0.0002786750339774535 }, "group4": { "name": "failed", "htmlName": "failed", "count": 14, "percentage": 0.0003901450475684349 }, "meanNumberOfRequestsPerSecond": { "total": 996.7802777777778, "ok": 996.7763888888888, "ko": 0.0038888888888888888 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 997, "avg_response_time": 85, "max_response_time": 5734, "min_response_time": 59, "num_requests": 3588419, "num_requests_pass": 3588405, "num_requests_percent_pass": 100.0 }, "options": "; testMode:open; testCase:idm.ManagedUsersPatch; duration:3600; throughput:1000", "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": 76, "total": 76 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 68, "total": 68 }, "minResponseTime": { "ko": 0, "ok": 60, "total": 60 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 68, "total": 68 }, "percentiles2": { "ko": 0, "ok": 72, "total": 72 }, "percentiles3": { "ko": 0, "ok": 75, "total": 75 }, "percentiles4": { "ko": 0, "ok": 76, "total": 76 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 8, "total": 8 } }, { "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": 119, "total": 119 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 112, "total": 112 }, "minResponseTime": { "ko": 0, "ok": 104, "total": 104 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 112, "total": 112 }, "percentiles2": { "ko": 0, "ok": 115, "total": 115 }, "percentiles3": { "ko": 0, "ok": 118, "total": 118 }, "percentiles4": { "ko": 0, "ok": 119, "total": 119 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 8, "total": 8 } }, { "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": 76, "total": 76 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 74, "total": 74 }, "minResponseTime": { "ko": 0, "ok": 71, "total": 71 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 74, "total": 74 }, "percentiles2": { "ko": 0, "ok": 75, "total": 75 }, "percentiles3": { "ko": 0, "ok": 76, "total": 76 }, "percentiles4": { "ko": 0, "ok": 76, "total": 76 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 3, "total": 3 } }, { "group1": { "count": 2, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 94, "total": 94 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 77, "total": 77 }, "minResponseTime": { "ko": 0, "ok": 59, "total": 59 }, "name": "User Authorize PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 77, "total": 77 }, "percentiles2": { "ko": 0, "ok": 85, "total": 85 }, "percentiles3": { "ko": 0, "ok": 92, "total": 92 }, "percentiles4": { "ko": 0, "ok": 94, "total": 94 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 18, "total": 18 } }, { "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": "User AccessToken PKCE stage", "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": 3588296, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.99685097211606 }, "group2": { "count": 89, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0024802078023993365 }, "group3": { "count": 10, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0002786750339774535 }, "group4": { "count": 14, "htmlName": "failed", "name": "failed", "percentage": 0.0003901450475684349 }, "maxResponseTime": { "ko": 10001, "ok": 5734, "total": 10001 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0038888888888888888, "ok": 996.7763888888888, "total": 996.7802777777778 }, "meanResponseTime": { "ko": 752, "ok": 85, "total": 85 }, "minResponseTime": { "ko": 37, "ok": 64, "total": 37 }, "name": "PatchManagedUser", "numberOfRequests": { "ko": 14, "ok": 3588395, "total": 3588409 }, "percentiles1": { "ko": 41, "ok": 80, "total": 80 }, "percentiles2": { "ko": 41, "ok": 92, "total": 92 }, "percentiles3": { "ko": 3528, "ok": 106, "total": 106 }, "percentiles4": { "ko": 8706, "ok": 157, "total": 157 }, "spikes": [], "standardDeviation": { "ko": 2565, "ok": 18, "total": 18 } } ], "global": { "group1": { "count": 3588306, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.99685098089158 }, "group2": { "count": 89, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.002480200890698661 }, "group3": { "count": 10, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0002786742573818721 }, "group4": { "count": 14, "htmlName": "failed", "name": "failed", "percentage": 0.0003901439603346209 }, "maxResponseTime": { "ko": 10001, "ok": 5734, "total": 10001 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0038888888888888888, "ok": 996.7791666666667, "total": 996.7830555555555 }, "meanResponseTime": { "ko": 752, "ok": 85, "total": 85 }, "minResponseTime": { "ko": 37, "ok": 59, "total": 37 }, "name": "All Requests", "numberOfRequests": { "ko": 14, "ok": 3588405, "total": 3588419 }, "percentiles1": { "ko": 41, "ok": 80, "total": 80 }, "percentiles2": { "ko": 41, "ok": 92, "total": 92 }, "percentiles3": { "ko": 3528, "ok": 106, "total": 106 }, "percentiles4": { "ko": 8706, "ok": 157, "total": 157 }, "standardDeviation": { "ko": 2565, "ok": 18, "total": 18 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 16:13:56] Reporting-patch-1000 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 16:13:56] Reporting-patch-1000 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/18_Reporting-patch-1000/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 16:13:56] Reporting-patch-1000 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped