--Task-- name: Reporting-patch-1800 enabled: True class_name: GatlingResultTask source_name: overseer-0 source_namespace: >default< target_name: overseer-0 target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates below loop: False interval: None dependencies: [] wait_for: ['Logs-parse-update-500', 'Logs-parse-update-1000', 'Logs-parse-update-1500', 'Logs-parse-update-1600', 'Logs-parse-update-1700', 'Logs-parse-patch-500', 'Logs-parse-patch-1000', 'Logs-parse-patch-1500', 'Logs-parse-patch-1700', 'Logs-parse-patch-1800', 'Reporting-update-500', 'Reporting-update-1000', 'Reporting-update-1500', 'Reporting-update-1600', 'Reporting-update-1700', 'Reporting-patch-500', 'Reporting-patch-1000', 'Reporting-patch-1500', 'Reporting-patch-1700'] preceding_task: None options: {'task_name': 'patch-1800'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 16:23:10 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 16:23:10 - 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-1800/stdout.txt Pod stderr file : /results/orders/Reporting-patch-1800/stderr.txt Pod log : /results/orders/Reporting-patch-1800 ________________________________________________________________________________ [2025-03-28 16:23:10] Reporting-patch-1800 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/21_Reporting-patch-1800/order.json { "name": "Reporting-patch-1800", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/patch-1800\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"patch-1800.tar.gz\"", "out-dir": "/results/orders/Reporting-patch-1800" } ________________________________________________________________________________ [2025-03-28 16:23:10] Reporting-patch-1800 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 16:23:10,574 INFO 2025-03-28 16:23:10,574 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:23:10,574 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 16:23:10,789 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:23:10,789 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:10,789 DEBUG --- stdout --- 2025-03-28 16:23:10,789 DEBUG WAITING 2025-03-28 16:23:10,789 DEBUG --- stderr --- 2025-03-28 16:23:10,789 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:23:10] Reporting-patch-1800 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 16:23:10,790 INFO 2025-03-28 16:23:10,790 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2025-03-28 16:23:10,790 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:23:11,093 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:23:11,093 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:11,093 DEBUG --- stdout --- 2025-03-28 16:23:11,093 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:23:11,093 DEBUG --- stderr --- 2025-03-28 16:23:11,093 DEBUG 2025-03-28 16:23:11,093 INFO 2025-03-28 16:23:11,093 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/21_Reporting-patch-1800/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 16:23:11,093 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:23:12,509 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:23:12,510 DEBUG --- stdout --- 2025-03-28 16:23:12,510 DEBUG 2025-03-28 16:23:12,510 DEBUG --- stderr --- 2025-03-28 16:23:12,510 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 16:23:12] Reporting-patch-1800 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 16:23:17,510 INFO 2025-03-28 16:23:17,510 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:23:17,510 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 16:23:17,726 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:23:22,988 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:23:28,203 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:23:33,418 INFO [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:23:38,632 INFO [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:23:43,847 INFO [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:23:49,063 INFO [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:23:54,322 INFO [loop_until]: Function succeeded after 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:23:59,537 INFO [loop_until]: Function succeeded after 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:24:04,752 INFO [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:24:09,967 INFO [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:24:15,181 INFO [loop_until]: Function succeeded after 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:24:20,400 INFO [loop_until]: Function succeeded after 1m 02s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:24:25,662 INFO [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:24:30,877 INFO [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:24:36,092 INFO [loop_until]: Function succeeded after 1m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:24:41,306 INFO [loop_until]: Function succeeded after 1m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:24:46,521 INFO [loop_until]: Function succeeded after 1m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:24:51,735 INFO [loop_until]: Function succeeded after 1m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:24:56,952 INFO [loop_until]: Function succeeded after 1m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:02,259 INFO [loop_until]: Function succeeded after 1m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:07,475 INFO [loop_until]: Function succeeded after 1m 49s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:12,689 INFO [loop_until]: Function succeeded after 1m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:17,904 INFO [loop_until]: Function succeeded after 2m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:23,119 INFO [loop_until]: Function succeeded after 2m 05s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:28,333 INFO [loop_until]: Function succeeded after 2m 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:33,551 INFO [loop_until]: Function succeeded after 2m 16s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:38,843 INFO [loop_until]: Function succeeded after 2m 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:44,058 INFO [loop_until]: Function succeeded after 2m 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:49,272 INFO [loop_until]: Function succeeded after 2m 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:54,486 INFO [loop_until]: Function succeeded after 2m 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:25:59,702 INFO [loop_until]: Function succeeded after 2m 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:26:04,916 INFO [loop_until]: Function succeeded after 2m 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:26:10,133 INFO [loop_until]: Function succeeded after 2m 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:26:15,351 INFO [loop_until]: Function succeeded after 2m 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:26:20,609 INFO [loop_until]: Function succeeded after 3m 03s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:26:25,823 INFO [loop_until]: Function succeeded after 3m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:26:31,038 INFO [loop_until]: Function succeeded after 3m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:26:36,253 INFO [loop_until]: Function succeeded after 3m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:26:41,468 INFO [loop_until]: Function succeeded after 3m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:26:46,683 INFO [loop_until]: Function succeeded after 3m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:26:51,950 INFO [loop_until]: Function succeeded after 3m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:26:57,166 INFO [loop_until]: Function succeeded after 3m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:27:02,380 INFO [loop_until]: Function succeeded after 3m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:27:07,595 INFO [loop_until]: Function succeeded after 3m 50s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:27:12,810 INFO [loop_until]: Function succeeded after 3m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:27:18,024 INFO [loop_until]: Function succeeded after 4m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:27:23,332 INFO [loop_until]: Function succeeded after 4m 05s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:27:28,547 INFO [loop_until]: Function succeeded after 4m 11s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:27:33,762 INFO [loop_until]: Function succeeded after 4m 16s (rc=0) - expected pattern found 2025-03-28 16:27:33,762 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:33,762 DEBUG --- stdout --- 2025-03-28 16:27:33,762 DEBUG WAITING 2025-03-28 16:27:33,762 DEBUG --- stderr --- 2025-03-28 16:27:33,762 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:27:33] Reporting-patch-1800 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 16:27:33,763 INFO 2025-03-28 16:27:33,763 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:27:33,763 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:27:34,063 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:27:34,064 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:34,064 DEBUG --- stdout --- 2025-03-28 16:27:34,064 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:27:34,064 DEBUG --- stderr --- 2025-03-28 16:27:34,064 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:27:34,065 INFO 2025-03-28 16:27:34,065 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-1800/patch-1800.tar.gz" ]; then echo "/results/gatling/patch-1800/patch-1800.tar.gz file found"; exit 0; else echo "/results/gatling/patch-1800/patch-1800.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/patch-1800/patch-1800.tar.gz file found" 2025-03-28 16:27:34,065 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:27:34,912 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:27:34,912 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:34,912 DEBUG --- stdout --- 2025-03-28 16:27:34,912 DEBUG /results/gatling/patch-1800/patch-1800.tar.gz file found 2025-03-28 16:27:34,912 DEBUG --- stderr --- 2025-03-28 16:27:34,912 DEBUG 2025-03-28 16:27:34,912 INFO 2025-03-28 16:27:34,913 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-1800/patch-1800.tar.gz 2025-03-28 16:27:35,721 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:27:35,721 DEBUG --- stdout --- 2025-03-28 16:27:35,721 DEBUG 320 /results/gatling/patch-1800/patch-1800.tar.gz 2025-03-28 16:27:35,721 DEBUG --- stderr --- 2025-03-28 16:27:35,721 DEBUG 2025-03-28 16:27:35,722 INFO - Download file (size 320k) source : /results/gatling/patch-1800/patch-1800.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1800.tar.gz 2025-03-28 16:27:35,722 INFO 2025-03-28 16:27:35,722 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/patch-1800/patch-1800.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1800.tar.gz 2025-03-28 16:27:35,722 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:27:36,722 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:36,722 DEBUG --- stdout --- 2025-03-28 16:27:36,723 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:27:36,723 DEBUG --- stderr --- 2025-03-28 16:27:36,723 DEBUG ________________________________________________________________________________ [2025-03-28 16:27:36] Reporting-patch-1800 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 16:27:36,723 INFO 2025-03-28 16:27:36,723 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/patch-1800.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/10_patch-1800/gatling 2025-03-28 16:27:36,738 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:27:36,738 DEBUG --- stdout --- 2025-03-28 16:27:36,738 DEBUG 2025-03-28 16:27:36,738 DEBUG --- stderr --- 2025-03-28 16:27:36,738 DEBUG 2025-03-28 16:27:36,738 INFO ________________________________________________________________________________ [2025-03-28 16:27:36] Reporting-patch-1800 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 16:27:36,739 INFO 2025-03-28 16:27:36,739 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:27:36,739 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:27:37,016 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:27:37,016 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:37,016 DEBUG --- stdout --- 2025-03-28 16:27:37,016 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:27:37,016 DEBUG --- stderr --- 2025-03-28 16:27:37,016 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:27:37,017 INFO 2025-03-28 16:27:37,017 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-1800/rc.txt" ]; then echo "/results/orders/Reporting-patch-1800/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1800/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1800/rc.txt file found" 2025-03-28 16:27:37,017 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:27:37,823 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:27:37,823 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:37,823 DEBUG --- stdout --- 2025-03-28 16:27:37,823 DEBUG /results/orders/Reporting-patch-1800/rc.txt file found 2025-03-28 16:27:37,823 DEBUG --- stderr --- 2025-03-28 16:27:37,824 DEBUG 2025-03-28 16:27:37,824 INFO 2025-03-28 16:27:37,824 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-1800/rc.txt 2025-03-28 16:27:38,636 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:27:38,636 DEBUG --- stdout --- 2025-03-28 16:27:38,636 DEBUG 4 /results/orders/Reporting-patch-1800/rc.txt 2025-03-28 16:27:38,636 DEBUG --- stderr --- 2025-03-28 16:27:38,636 DEBUG 2025-03-28 16:27:38,636 INFO - Download file (size 4k) source : /results/orders/Reporting-patch-1800/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/21_Reporting-patch-1800/rc.txt 2025-03-28 16:27:38,637 INFO 2025-03-28 16:27:38,637 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-1800/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/21_Reporting-patch-1800/rc.txt 2025-03-28 16:27:38,637 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:27:39,357 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:39,357 DEBUG --- stdout --- 2025-03-28 16:27:39,357 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:27:39,357 DEBUG --- stderr --- 2025-03-28 16:27:39,357 DEBUG 2025-03-28 16:27:39,357 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/21_Reporting-patch-1800/rc.txt is small (1 lines), printing all lines: 2025-03-28 16:27:39,357 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 16:27:39] Reporting-patch-1800 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:27:39] Reporting-patch-1800 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 16:27:39,595 INFO 2025-03-28 16:27:39,595 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:27:39,595 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:27:39,873 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:27:39,873 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:39,873 DEBUG --- stdout --- 2025-03-28 16:27:39,873 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:27:39,873 DEBUG --- stderr --- 2025-03-28 16:27:39,873 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:27:39,874 INFO 2025-03-28 16:27:39,874 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-1800/stdout.txt" ]; then echo "/results/orders/Reporting-patch-1800/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1800/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1800/stdout.txt file found" 2025-03-28 16:27:39,874 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:27:40,810 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:27:40,810 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:40,810 DEBUG --- stdout --- 2025-03-28 16:27:40,810 DEBUG /results/orders/Reporting-patch-1800/stdout.txt file found 2025-03-28 16:27:40,810 DEBUG --- stderr --- 2025-03-28 16:27:40,810 DEBUG 2025-03-28 16:27:40,810 INFO 2025-03-28 16:27:40,811 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-1800/stdout.txt 2025-03-28 16:27:41,619 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:27:41,619 DEBUG --- stdout --- 2025-03-28 16:27:41,619 DEBUG 8 /results/orders/Reporting-patch-1800/stdout.txt 2025-03-28 16:27:41,619 DEBUG --- stderr --- 2025-03-28 16:27:41,619 DEBUG 2025-03-28 16:27:41,619 INFO - Download file (size 8k) source : /results/orders/Reporting-patch-1800/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/21_Reporting-patch-1800/stdout.txt 2025-03-28 16:27:41,620 INFO 2025-03-28 16:27:41,620 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-1800/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/21_Reporting-patch-1800/stdout.txt 2025-03-28 16:27:41,620 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:27:42,385 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:42,386 DEBUG --- stdout --- 2025-03-28 16:27:42,386 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:27:42,386 DEBUG --- stderr --- 2025-03-28 16:27:42,386 DEBUG 2025-03-28 16:27:42,386 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/21_Reporting-patch-1800/stdout.txt is small (95 lines), printing all lines: 2025-03-28 16:27:42,386 INFO ----- output ----- 2025-03-28 16:23:13,138 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp6gormvi8 2025-03-28 16:23:13,139 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/patch-1800/loadtest-20250328144923434" -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/tmp6gormvi8/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/tmp6gormvi8/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:23:14,964 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 16:23:14,966 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 16:23:14,966 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:23:14,966 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 16:23:14,972 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 16:23:14,972 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 16:23:14,979 |-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:23:14,979 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 16:23:14,980 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 16:23:14,980 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 16:23:14,983 |-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:23:14,987 |-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:23:15,030 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 16:23:15,030 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 16:23:15,068 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 16:23:15,068 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 16:23:15,074 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 16:23:15,095 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 16:23:15,095 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 16:23:15,096 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 16:23:15,096 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 16:23:15,096 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 116 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY Parsing log file(s)... Parsing log file(s) done in 256s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 6451820 (OK=6451787 KO=33 ) > min response time 37 (OK=57 KO=37 ) > max response time 53144 (OK=53144 KO=11051 ) > mean response time 169 (OK=169 KO=3408 ) > std deviation 212 (OK=211 KO=4734 ) > response time 50th percentile 129 (OK=129 KO=42 ) > response time 75th percentile 164 (OK=164 KO=10000 ) > response time 95th percentile 339 (OK=339 KO=10010 ) > response time 99th percentile 842 (OK=842 KO=10723 ) > mean requests/sec 1792.17 (OK=1792.16 KO=0.01 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 6383022 ( 98.93%) > 800 ms <= t < 1200 ms 27000 ( 0.42%) > t >= 1200 ms 41765 ( 0.65%) > failed 33 ( 0%) ---- Errors -------------------------------------------------------------------- > status.find.in(200), but actually found 502 20 (60.61%) > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 7 (21.21%) r 10000ms > status.find.in(200), but actually found 503 2 ( 6.06%) > i.n.c.ConnectTimeoutException: connection timed out after 1000 2 ( 6.06%) 0 ms: openam-perf-200m.forgeblocks.com/34.107.203.37:443 > j.i.IOException: Premature close 2 ( 6.06%) ================================================================================ Reports generated, please open the following file: file:///results/gatling/patch-1800/loadtest-20250328144923434/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 04:18 min [INFO] Finished at: 2025-03-28T16:27:32Z [INFO] ------------------------------------------------------------------------ 2025-03-28 16:27:32,804 INFO Gatling simulation run ended successfully 2025-03-28 16:27:32,804 INFO Creating archive patch-1800.tar.gz with report files 2025-03-28 16:27:32,913 INFO Archive /results/gatling/patch-1800/patch-1800.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 16:27:42] Reporting-patch-1800 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 16:27:42] Reporting-patch-1800 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 16:27:42,387 INFO 2025-03-28 16:27:42,387 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:27:42,387 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:27:42,663 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:27:42,663 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:42,663 DEBUG --- stdout --- 2025-03-28 16:27:42,663 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:27:42,663 DEBUG --- stderr --- 2025-03-28 16:27:42,663 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:27:42,664 INFO 2025-03-28 16:27:42,664 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-1800/stderr.txt" ]; then echo "/results/orders/Reporting-patch-1800/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-patch-1800/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-patch-1800/stderr.txt file found" 2025-03-28 16:27:42,664 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:27:43,467 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:27:43,467 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:43,467 DEBUG --- stdout --- 2025-03-28 16:27:43,467 DEBUG /results/orders/Reporting-patch-1800/stderr.txt file found 2025-03-28 16:27:43,467 DEBUG --- stderr --- 2025-03-28 16:27:43,467 DEBUG 2025-03-28 16:27:43,467 INFO 2025-03-28 16:27:43,468 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-1800/stderr.txt 2025-03-28 16:27:44,270 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:27:44,270 DEBUG --- stdout --- 2025-03-28 16:27:44,270 DEBUG 0 /results/orders/Reporting-patch-1800/stderr.txt 2025-03-28 16:27:44,270 DEBUG --- stderr --- 2025-03-28 16:27:44,270 DEBUG 2025-03-28 16:27:44,270 INFO - Download file (size 0k) source : /results/orders/Reporting-patch-1800/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/21_Reporting-patch-1800/stderr.txt 2025-03-28 16:27:44,271 INFO 2025-03-28 16:27:44,271 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-1800/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/21_Reporting-patch-1800/stderr.txt 2025-03-28 16:27:44,271 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:27:44,991 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:27:44,991 DEBUG --- stdout --- 2025-03-28 16:27:44,992 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:27:44,992 DEBUG --- stderr --- 2025-03-28 16:27:44,992 DEBUG 2025-03-28 16:27:44,992 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/21_Reporting-patch-1800/stderr.txt is small (0 lines), printing all lines: 2025-03-28 16:27:44,992 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 16:27:44] Reporting-patch-1800 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 16:27:44] Reporting-patch-1800 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/10_patch-1800/gatling/req_patchmanageduse--1542793534.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 16:27:44] Reporting-patch-1800 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 6451820, "ok": 6451787, "ko": 33 }, "minResponseTime": { "total": 37, "ok": 57, "ko": 37 }, "maxResponseTime": { "total": 53144, "ok": 53144, "ko": 11051 }, "meanResponseTime": { "total": 169, "ok": 169, "ko": 3408 }, "standardDeviation": { "total": 212, "ok": 211, "ko": 4734 }, "percentiles1": { "total": 129, "ok": 129, "ko": 42 }, "percentiles2": { "total": 164, "ok": 164, "ko": 10000 }, "percentiles3": { "total": 339, "ok": 339, "ko": 10010 }, "percentiles4": { "total": 842, "ok": 842, "ko": 10723 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 6383022, "percentage": 98.93366522934613 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 27000, "percentage": 0.4184865665812127 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 41765, "percentage": 0.6473367204912722 }, "group4": { "name": "failed", "htmlName": "failed", "count": 33, "percentage": 0.0005114835813770378 }, "meanNumberOfRequestsPerSecond": { "total": 1792.1722222222222, "ok": 1792.1630555555555, "ko": 0.009166666666666667 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 71, "ok": 71, "ko": 0 }, "maxResponseTime": { "total": 72, "ok": 72, "ko": 0 }, "meanResponseTime": { "total": 72, "ok": 72, "ko": 0 }, "standardDeviation": { "total": 1, "ok": 1, "ko": 0 }, "percentiles1": { "total": 72, "ok": 72, "ko": 0 }, "percentiles2": { "total": 72, "ok": 72, "ko": 0 }, "percentiles3": { "total": 72, "ok": 72, "ko": 0 }, "percentiles4": { "total": 72, "ok": 72, "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": 110, "ok": 110, "ko": 0 }, "maxResponseTime": { "total": 115, "ok": 115, "ko": 0 }, "meanResponseTime": { "total": 113, "ok": 113, "ko": 0 }, "standardDeviation": { "total": 3, "ok": 3, "ko": 0 }, "percentiles1": { "total": 113, "ok": 113, "ko": 0 }, "percentiles2": { "total": 114, "ok": 114, "ko": 0 }, "percentiles3": { "total": 115, "ok": 115, "ko": 0 }, "percentiles4": { "total": 115, "ok": 115, "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": 74, "ok": 74, "ko": 0 }, "meanResponseTime": { "total": 74, "ok": 74, "ko": 0 }, "standardDeviation": { "total": 1, "ok": 1, "ko": 0 }, "percentiles1": { "total": 74, "ok": 74, "ko": 0 }, "percentiles2": { "total": 74, "ok": 74, "ko": 0 }, "percentiles3": { "total": 74, "ok": 74, "ko": 0 }, "percentiles4": { "total": 74, "ok": 74, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "User Authorize PKCE stage", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "maxResponseTime": { "total": 68, "ok": 68, "ko": 0 }, "meanResponseTime": { "total": 67, "ok": 67, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 67, "ok": 67, "ko": 0 }, "percentiles2": { "total": 67, "ok": 67, "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": "User AccessToken PKCE stage", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 57, "ok": 57, "ko": 0 }, "maxResponseTime": { "total": 69, "ok": 69, "ko": 0 }, "meanResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "standardDeviation": { "total": 6, "ok": 6, "ko": 0 }, "percentiles1": { "total": 63, "ok": 63, "ko": 0 }, "percentiles2": { "total": 66, "ok": 66, "ko": 0 }, "percentiles3": { "total": 68, "ok": 68, "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": "PatchManagedUser", "numberOfRequests": { "total": 6451810, "ok": 6451777, "ko": 33 }, "minResponseTime": { "total": 37, "ok": 65, "ko": 37 }, "maxResponseTime": { "total": 53144, "ok": 53144, "ko": 11051 }, "meanResponseTime": { "total": 169, "ok": 169, "ko": 3408 }, "standardDeviation": { "total": 212, "ok": 211, "ko": 4734 }, "percentiles1": { "total": 129, "ok": 129, "ko": 42 }, "percentiles2": { "total": 164, "ok": 164, "ko": 10000 }, "percentiles3": { "total": 339, "ok": 339, "ko": 10010 }, "percentiles4": { "total": 842, "ok": 842, "ko": 10723 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 6383012, "percentage": 98.93366357657773 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 27000, "percentage": 0.41848721521557514 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 41765, "percentage": 0.6473377238325368 }, "group4": { "name": "failed", "htmlName": "failed", "count": 33, "percentage": 0.0005114843741523697 }, "meanNumberOfRequestsPerSecond": { "total": 1792.1694444444445, "ok": 1792.1602777777778, "ko": 0.009166666666666667 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 1792, "avg_response_time": 169, "max_response_time": 53144, "min_response_time": 57, "num_requests": 6451820, "num_requests_pass": 6451787, "num_requests_percent_pass": 99.999 }, "options": "; testMode:open; testCase:idm.ManagedUsersPatch; duration:3600; throughput:1800", "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": 72, "total": 72 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 72, "total": 72 }, "minResponseTime": { "ko": 0, "ok": 71, "total": 71 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 72, "total": 72 }, "percentiles2": { "ko": 0, "ok": 72, "total": 72 }, "percentiles3": { "ko": 0, "ok": 72, "total": 72 }, "percentiles4": { "ko": 0, "ok": 72, "total": 72 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 1, "total": 1 } }, { "group1": { "count": 2, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 115, "total": 115 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 113, "total": 113 }, "minResponseTime": { "ko": 0, "ok": 110, "total": 110 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 113, "total": 113 }, "percentiles2": { "ko": 0, "ok": 114, "total": 114 }, "percentiles3": { "ko": 0, "ok": 115, "total": 115 }, "percentiles4": { "ko": 0, "ok": 115, "total": 115 }, "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": 74, "total": 74 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 74, "total": 74 }, "minResponseTime": { "ko": 0, "ok": 73, "total": 73 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 74, "total": 74 }, "percentiles2": { "ko": 0, "ok": 74, "total": 74 }, "percentiles3": { "ko": 0, "ok": 74, "total": 74 }, "percentiles4": { "ko": 0, "ok": 74, "total": 74 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 1, "total": 1 } }, { "group1": { "count": 2, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 68, "total": 68 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 67, "total": 67 }, "minResponseTime": { "ko": 0, "ok": 65, "total": 65 }, "name": "User Authorize PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 67, "total": 67 }, "percentiles2": { "ko": 0, "ok": 67, "total": 67 }, "percentiles3": { "ko": 0, "ok": 68, "total": 68 }, "percentiles4": { "ko": 0, "ok": 68, "total": 68 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } }, { "group1": { "count": 2, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 69, "total": 69 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "minResponseTime": { "ko": 0, "ok": 57, "total": 57 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 63, "total": 63 }, "percentiles2": { "ko": 0, "ok": 66, "total": 66 }, "percentiles3": { "ko": 0, "ok": 68, "total": 68 }, "percentiles4": { "ko": 0, "ok": 69, "total": 69 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 6, "total": 6 } }, { "group1": { "count": 6383012, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 98.93366357657773 }, "group2": { "count": 27000, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.41848721521557514 }, "group3": { "count": 41765, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.6473377238325368 }, "group4": { "count": 33, "htmlName": "failed", "name": "failed", "percentage": 0.0005114843741523697 }, "maxResponseTime": { "ko": 11051, "ok": 53144, "total": 53144 }, "meanNumberOfRequestsPerSecond": { "ko": 0.009166666666666667, "ok": 1792.1602777777778, "total": 1792.1694444444445 }, "meanResponseTime": { "ko": 3408, "ok": 169, "total": 169 }, "minResponseTime": { "ko": 37, "ok": 65, "total": 37 }, "name": "PatchManagedUser", "numberOfRequests": { "ko": 33, "ok": 6451777, "total": 6451810 }, "percentiles1": { "ko": 42, "ok": 129, "total": 129 }, "percentiles2": { "ko": 10000, "ok": 164, "total": 164 }, "percentiles3": { "ko": 10010, "ok": 339, "total": 339 }, "percentiles4": { "ko": 10723, "ok": 842, "total": 842 }, "spikes": [], "standardDeviation": { "ko": 4734, "ok": 211, "total": 212 } } ], "global": { "group1": { "count": 6383022, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 98.93366522934613 }, "group2": { "count": 27000, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.4184865665812127 }, "group3": { "count": 41765, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.6473367204912722 }, "group4": { "count": 33, "htmlName": "failed", "name": "failed", "percentage": 0.0005114835813770378 }, "maxResponseTime": { "ko": 11051, "ok": 53144, "total": 53144 }, "meanNumberOfRequestsPerSecond": { "ko": 0.009166666666666667, "ok": 1792.1630555555555, "total": 1792.1722222222222 }, "meanResponseTime": { "ko": 3408, "ok": 169, "total": 169 }, "minResponseTime": { "ko": 37, "ok": 57, "total": 37 }, "name": "All Requests", "numberOfRequests": { "ko": 33, "ok": 6451787, "total": 6451820 }, "percentiles1": { "ko": 42, "ok": 129, "total": 129 }, "percentiles2": { "ko": 10000, "ok": 164, "total": 164 }, "percentiles3": { "ko": 10010, "ok": 339, "total": 339 }, "percentiles4": { "ko": 10723, "ok": 842, "total": 842 }, "standardDeviation": { "ko": 4734, "ok": 211, "total": 212 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 16:27:44] Reporting-patch-1800 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 16:27:44] Reporting-patch-1800 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/21_Reporting-patch-1800/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 16:27:44] Reporting-patch-1800 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped