--Task-- name: Reporting-update-1600 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'] preceding_task: None options: {'task_name': 'update-1600'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 16:00:20 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 16:00:20 - INFO: timeout was set to GatlingResultTask default (half of global_duration or 3600 seconds (whatever is higher)), because it was unset Pod stdout file : /results/orders/Reporting-update-1600/stdout.txt Pod stderr file : /results/orders/Reporting-update-1600/stderr.txt Pod log : /results/orders/Reporting-update-1600 ________________________________________________________________________________ [2025-03-28 16:00:20] Reporting-update-1600 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/15_Reporting-update-1600/order.json { "name": "Reporting-update-1600", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/update-1600\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"update-1600.tar.gz\"", "out-dir": "/results/orders/Reporting-update-1600" } ________________________________________________________________________________ [2025-03-28 16:00:20] Reporting-update-1600 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 16:00:20,914 INFO 2025-03-28 16:00:20,914 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:00:20,914 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 16:00:21,128 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:00:21,128 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:21,128 DEBUG --- stdout --- 2025-03-28 16:00:21,129 DEBUG WAITING 2025-03-28 16:00:21,129 DEBUG --- stderr --- 2025-03-28 16:00:21,129 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2025-03-28 16:00:21] Reporting-update-1600 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 16:00:21,129 INFO 2025-03-28 16:00:21,129 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2025-03-28 16:00:21,129 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:00:21,408 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:00:21,408 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:21,408 DEBUG --- stdout --- 2025-03-28 16:00:21,408 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:00:21,408 DEBUG --- stderr --- 2025-03-28 16:00:21,408 DEBUG 2025-03-28 16:00:21,409 INFO 2025-03-28 16:00:21,409 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/15_Reporting-update-1600/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 16:00:21,409 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:00:22,773 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:00:22,773 DEBUG --- stdout --- 2025-03-28 16:00:22,773 DEBUG 2025-03-28 16:00:22,773 DEBUG --- stderr --- 2025-03-28 16:00:22,774 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 16:00:22] Reporting-update-1600 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 16:00:27,774 INFO 2025-03-28 16:00:27,774 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:00:27,774 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 16:00:27,989 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:00:33,205 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:00:38,420 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:00:43,638 INFO [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:00:48,896 INFO [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:00:54,111 INFO [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:00:59,327 INFO [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:01:04,542 INFO [loop_until]: Function succeeded after 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:01:09,759 INFO [loop_until]: Function succeeded after 41s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:01:14,975 INFO [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:01:20,192 INFO [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:01:25,449 INFO [loop_until]: Function succeeded after 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:01:30,664 INFO [loop_until]: Function succeeded after 1m 02s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:01:35,880 INFO [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:01:41,095 INFO [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:01:46,310 INFO [loop_until]: Function succeeded after 1m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:01:51,524 INFO [loop_until]: Function succeeded after 1m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:01:56,741 INFO [loop_until]: Function succeeded after 1m 28s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:01,959 INFO [loop_until]: Function succeeded after 1m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:07,225 INFO [loop_until]: Function succeeded after 1m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:12,445 INFO [loop_until]: Function succeeded after 1m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:17,659 INFO [loop_until]: Function succeeded after 1m 49s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:22,874 INFO [loop_until]: Function succeeded after 1m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:28,089 INFO [loop_until]: Function succeeded after 2m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:33,304 INFO [loop_until]: Function succeeded after 2m 05s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:38,562 INFO [loop_until]: Function succeeded after 2m 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:43,820 INFO [loop_until]: Function succeeded after 2m 16s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:49,035 INFO [loop_until]: Function succeeded after 2m 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:54,250 INFO [loop_until]: Function succeeded after 2m 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:02:59,464 INFO [loop_until]: Function succeeded after 2m 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:03:04,679 INFO [loop_until]: Function succeeded after 2m 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:03:09,939 INFO [loop_until]: Function succeeded after 2m 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:03:15,153 INFO [loop_until]: Function succeeded after 2m 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:03:20,368 INFO [loop_until]: Function succeeded after 2m 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:03:25,583 INFO [loop_until]: Function succeeded after 2m 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:03:30,797 INFO [loop_until]: Function succeeded after 3m 03s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:03:36,013 INFO [loop_until]: Function succeeded after 3m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:03:41,281 INFO [loop_until]: Function succeeded after 3m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:03:46,496 INFO [loop_until]: Function succeeded after 3m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:03:51,711 INFO [loop_until]: Function succeeded after 3m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:03:56,926 INFO [loop_until]: Function succeeded after 3m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:04:02,141 INFO [loop_until]: Function succeeded after 3m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:04:07,358 INFO [loop_until]: Function succeeded after 3m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:04:12,627 INFO [loop_until]: Function succeeded after 3m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:04:17,842 INFO [loop_until]: Function succeeded after 3m 50s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:04:23,056 INFO [loop_until]: Function succeeded after 3m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:04:28,271 INFO [loop_until]: Function succeeded after 4m (rc=0) - expected pattern found 2025-03-28 16:04:28,271 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:28,272 DEBUG --- stdout --- 2025-03-28 16:04:28,272 DEBUG WAITING 2025-03-28 16:04:28,272 DEBUG --- stderr --- 2025-03-28 16:04:28,272 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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2025-03-28 16:04:28] Reporting-update-1600 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 16:04:28,273 INFO 2025-03-28 16:04:28,273 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:04:28,273 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:04:28,579 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:04:28,580 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:28,580 DEBUG --- stdout --- 2025-03-28 16:04:28,580 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:04:28,580 DEBUG --- stderr --- 2025-03-28 16:04:28,580 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:04:28,581 INFO 2025-03-28 16:04:28,581 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/gatling/update-1600/update-1600.tar.gz" ]; then echo "/results/gatling/update-1600/update-1600.tar.gz file found"; exit 0; else echo "/results/gatling/update-1600/update-1600.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/update-1600/update-1600.tar.gz file found" 2025-03-28 16:04:28,581 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:04:29,430 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:04:29,430 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:29,430 DEBUG --- stdout --- 2025-03-28 16:04:29,430 DEBUG /results/gatling/update-1600/update-1600.tar.gz file found 2025-03-28 16:04:29,430 DEBUG --- stderr --- 2025-03-28 16:04:29,430 DEBUG 2025-03-28 16:04:29,430 INFO 2025-03-28 16:04:29,430 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/gatling/update-1600/update-1600.tar.gz 2025-03-28 16:04:30,227 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:04:30,227 DEBUG --- stdout --- 2025-03-28 16:04:30,227 DEBUG 312 /results/gatling/update-1600/update-1600.tar.gz 2025-03-28 16:04:30,227 DEBUG --- stderr --- 2025-03-28 16:04:30,227 DEBUG 2025-03-28 16:04:30,227 INFO - Download file (size 312k) source : /results/gatling/update-1600/update-1600.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-1600.tar.gz 2025-03-28 16:04:30,228 INFO 2025-03-28 16:04:30,228 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/update-1600/update-1600.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-1600.tar.gz 2025-03-28 16:04:30,228 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:04:31,229 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:31,229 DEBUG --- stdout --- 2025-03-28 16:04:31,229 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:04:31,229 DEBUG --- stderr --- 2025-03-28 16:04:31,229 DEBUG ________________________________________________________________________________ [2025-03-28 16:04:31] Reporting-update-1600 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 16:04:31,230 INFO 2025-03-28 16:04:31,230 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-1600.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/04_update-1600/gatling 2025-03-28 16:04:31,244 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:04:31,244 DEBUG --- stdout --- 2025-03-28 16:04:31,245 DEBUG 2025-03-28 16:04:31,245 DEBUG --- stderr --- 2025-03-28 16:04:31,245 DEBUG 2025-03-28 16:04:31,245 INFO ________________________________________________________________________________ [2025-03-28 16:04:31] Reporting-update-1600 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 16:04:31,245 INFO 2025-03-28 16:04:31,245 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:04:31,245 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:04:31,523 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:04:31,523 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:31,523 DEBUG --- stdout --- 2025-03-28 16:04:31,523 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:04:31,523 DEBUG --- stderr --- 2025-03-28 16:04:31,523 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:04:31,524 INFO 2025-03-28 16:04:31,524 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/Reporting-update-1600/rc.txt" ]; then echo "/results/orders/Reporting-update-1600/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-update-1600/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-1600/rc.txt file found" 2025-03-28 16:04:31,524 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:04:32,332 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:04:32,332 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:32,332 DEBUG --- stdout --- 2025-03-28 16:04:32,333 DEBUG /results/orders/Reporting-update-1600/rc.txt file found 2025-03-28 16:04:32,333 DEBUG --- stderr --- 2025-03-28 16:04:32,333 DEBUG 2025-03-28 16:04:32,333 INFO 2025-03-28 16:04:32,333 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/Reporting-update-1600/rc.txt 2025-03-28 16:04:33,154 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:04:33,154 DEBUG --- stdout --- 2025-03-28 16:04:33,154 DEBUG 4 /results/orders/Reporting-update-1600/rc.txt 2025-03-28 16:04:33,154 DEBUG --- stderr --- 2025-03-28 16:04:33,154 DEBUG 2025-03-28 16:04:33,154 INFO - Download file (size 4k) source : /results/orders/Reporting-update-1600/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-update-1600/rc.txt 2025-03-28 16:04:33,154 INFO 2025-03-28 16:04:33,154 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-update-1600/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-update-1600/rc.txt 2025-03-28 16:04:33,154 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:04:33,915 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:33,915 DEBUG --- stdout --- 2025-03-28 16:04:33,915 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:04:33,915 DEBUG --- stderr --- 2025-03-28 16:04:33,916 DEBUG 2025-03-28 16:04:33,916 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-update-1600/rc.txt is small (1 lines), printing all lines: 2025-03-28 16:04:33,916 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 16:04:33] Reporting-update-1600 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:04:34] Reporting-update-1600 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 16:04:34,154 INFO 2025-03-28 16:04:34,154 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:04:34,154 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:04:34,434 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:04:34,434 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:34,434 DEBUG --- stdout --- 2025-03-28 16:04:34,434 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:04:34,434 DEBUG --- stderr --- 2025-03-28 16:04:34,434 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:04:34,435 INFO 2025-03-28 16:04:34,435 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/Reporting-update-1600/stdout.txt" ]; then echo "/results/orders/Reporting-update-1600/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-update-1600/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-1600/stdout.txt file found" 2025-03-28 16:04:34,435 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:04:35,234 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:04:35,234 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:35,234 DEBUG --- stdout --- 2025-03-28 16:04:35,234 DEBUG /results/orders/Reporting-update-1600/stdout.txt file found 2025-03-28 16:04:35,235 DEBUG --- stderr --- 2025-03-28 16:04:35,235 DEBUG 2025-03-28 16:04:35,235 INFO 2025-03-28 16:04:35,235 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/Reporting-update-1600/stdout.txt 2025-03-28 16:04:36,035 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:04:36,035 DEBUG --- stdout --- 2025-03-28 16:04:36,035 DEBUG 8 /results/orders/Reporting-update-1600/stdout.txt 2025-03-28 16:04:36,035 DEBUG --- stderr --- 2025-03-28 16:04:36,035 DEBUG 2025-03-28 16:04:36,035 INFO - Download file (size 8k) source : /results/orders/Reporting-update-1600/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-update-1600/stdout.txt 2025-03-28 16:04:36,036 INFO 2025-03-28 16:04:36,036 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-update-1600/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-update-1600/stdout.txt 2025-03-28 16:04:36,036 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:04:36,799 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:36,799 DEBUG --- stdout --- 2025-03-28 16:04:36,799 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:04:36,799 DEBUG --- stderr --- 2025-03-28 16:04:36,799 DEBUG 2025-03-28 16:04:36,799 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-update-1600/stdout.txt is small (96 lines), printing all lines: 2025-03-28 16:04:36,799 INFO ----- output ----- 2025-03-28 16:00:23,713 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpsuoab7gr 2025-03-28 16:00:23,715 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/update-1600/loadtest-20250328084508713" -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/tmpsuoab7gr/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/tmpsuoab7gr/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:00:25,532 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 16:00:25,534 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 16:00:25,534 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:00:25,535 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 16:00:25,540 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 16:00:25,540 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 16:00:25,548 |-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:00:25,548 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 16:00:25,548 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 16:00:25,549 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 16:00:25,551 |-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:00:25,556 |-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:00:25,600 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 16:00:25,600 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 16:00:25,641 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 16:00:25,641 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 16:00:25,647 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 16:00:25,668 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 16:00:25,669 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 16:00:25,669 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 16:00:25,669 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 16:00:25,669 |-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 237s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 5737239 (OK=5737222 KO=17 ) > min response time 37 (OK=63 KO=37 ) > max response time 60000 (OK=7513 KO=60000 ) > mean response time 122 (OK=122 KO=6499 ) > std deviation 67 (OK=62 KO=14106 ) > response time 50th percentile 109 (OK=109 KO=41 ) > response time 75th percentile 128 (OK=128 KO=10000 ) > response time 95th percentile 189 (OK=189 KO=20030 ) > response time 99th percentile 362 (OK=362 KO=52006 ) > mean requests/sec 1593.68 (OK=1593.67 KO=0 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 5731664 ( 99.9%) > 800 ms <= t < 1200 ms 3518 ( 0.06%) > t >= 1200 ms 2040 ( 0.04%) > failed 17 ( 0%) ---- Errors -------------------------------------------------------------------- > status.find.in(200), but actually found 502 11 (64.71%) > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 2 (11.76%) r 10000ms > i.n.c.ConnectTimeoutException: connection timed out after 1000 2 (11.76%) 0 ms: openam-perf-200m.forgeblocks.com/34.107.203.37:443 > j.i.IOException: Premature close 1 ( 5.88%) > Request timeout to openam-perf-200m.forgeblocks.com/34.107.203 1 ( 5.88%) .37:443 after 60000 ms ================================================================================ Reports generated, please open the following file: file:///results/gatling/update-1600/loadtest-20250328084508713/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 03:59 min [INFO] Finished at: 2025-03-28T16:04:23Z [INFO] ------------------------------------------------------------------------ 2025-03-28 16:04:23,838 INFO Gatling simulation run ended successfully 2025-03-28 16:04:23,838 INFO Creating archive update-1600.tar.gz with report files 2025-03-28 16:04:23,948 INFO Archive /results/gatling/update-1600/update-1600.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 16:04:36] Reporting-update-1600 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 16:04:36] Reporting-update-1600 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 16:04:36,800 INFO 2025-03-28 16:04:36,800 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:04:36,800 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:04:37,096 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:04:37,096 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:37,096 DEBUG --- stdout --- 2025-03-28 16:04:37,096 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:04:37,096 DEBUG --- stderr --- 2025-03-28 16:04:37,096 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:04:37,097 INFO 2025-03-28 16:04:37,097 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/Reporting-update-1600/stderr.txt" ]; then echo "/results/orders/Reporting-update-1600/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-update-1600/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-1600/stderr.txt file found" 2025-03-28 16:04:37,097 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:04:37,896 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:04:37,897 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:37,897 DEBUG --- stdout --- 2025-03-28 16:04:37,897 DEBUG /results/orders/Reporting-update-1600/stderr.txt file found 2025-03-28 16:04:37,897 DEBUG --- stderr --- 2025-03-28 16:04:37,897 DEBUG 2025-03-28 16:04:37,897 INFO 2025-03-28 16:04:37,897 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/Reporting-update-1600/stderr.txt 2025-03-28 16:04:38,694 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:04:38,694 DEBUG --- stdout --- 2025-03-28 16:04:38,694 DEBUG 0 /results/orders/Reporting-update-1600/stderr.txt 2025-03-28 16:04:38,694 DEBUG --- stderr --- 2025-03-28 16:04:38,694 DEBUG 2025-03-28 16:04:38,694 INFO - Download file (size 0k) source : /results/orders/Reporting-update-1600/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-update-1600/stderr.txt 2025-03-28 16:04:38,695 INFO 2025-03-28 16:04:38,695 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-update-1600/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-update-1600/stderr.txt 2025-03-28 16:04:38,695 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:04:39,426 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:39,426 DEBUG --- stdout --- 2025-03-28 16:04:39,426 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:04:39,426 DEBUG --- stderr --- 2025-03-28 16:04:39,426 DEBUG 2025-03-28 16:04:39,426 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/15_Reporting-update-1600/stderr.txt is small (0 lines), printing all lines: 2025-03-28 16:04:39,426 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 16:04:39] Reporting-update-1600 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 16:04:39] Reporting-update-1600 step13 : Analyze graphs for spikes ________________________________________________________________________________ Analyze Gatling graphs for spikes checking parameters open overall stats JSON file checking simulation request restLoginInitiate checking simulation request restLoginUsernamePasswordCallback checking simulation request Skip 2FA checking simulation request User Authorize PKCE stage checking simulation request User AccessToken PKCE stage checking simulation request UpdateManagedUser [warn] responseTimePercentiles was not found in /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/04_update-1600/gatling/req_updatemanagedus--1469575327.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 16:04:39] Reporting-update-1600 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 5737239, "ok": 5737222, "ko": 17 }, "minResponseTime": { "total": 37, "ok": 63, "ko": 37 }, "maxResponseTime": { "total": 60000, "ok": 7513, "ko": 60000 }, "meanResponseTime": { "total": 122, "ok": 122, "ko": 6499 }, "standardDeviation": { "total": 67, "ok": 62, "ko": 14106 }, "percentiles1": { "total": 109, "ok": 109, "ko": 41 }, "percentiles2": { "total": 128, "ok": 128, "ko": 10000 }, "percentiles3": { "total": 189, "ok": 189, "ko": 20030 }, "percentiles4": { "total": 362, "ok": 362, "ko": 52006 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 5731664, "percentage": 99.90282782362736 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 3518, "percentage": 0.06131869353882591 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 2040, "percentage": 0.03555717305832998 }, "group4": { "name": "failed", "htmlName": "failed", "count": 17, "percentage": 0.00029630977548608314 }, "meanNumberOfRequestsPerSecond": { "total": 1593.6775, "ok": 1593.6727777777778, "ko": 0.004722222222222222 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "maxResponseTime": { "total": 69, "ok": 69, "ko": 0 }, "meanResponseTime": { "total": 66, "ok": 66, "ko": 0 }, "standardDeviation": { "total": 3, "ok": 3, "ko": 0 }, "percentiles1": { "total": 66, "ok": 66, "ko": 0 }, "percentiles2": { "total": 68, "ok": 68, "ko": 0 }, "percentiles3": { "total": 69, "ok": 69, "ko": 0 }, "percentiles4": { "total": 69, "ok": 69, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 111, "ok": 111, "ko": 0 }, "maxResponseTime": { "total": 131, "ok": 131, "ko": 0 }, "meanResponseTime": { "total": 121, "ok": 121, "ko": 0 }, "standardDeviation": { "total": 10, "ok": 10, "ko": 0 }, "percentiles1": { "total": 121, "ok": 121, "ko": 0 }, "percentiles2": { "total": 126, "ok": 126, "ko": 0 }, "percentiles3": { "total": 130, "ok": 130, "ko": 0 }, "percentiles4": { "total": 131, "ok": 131, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "Skip 2FA", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 70, "ok": 70, "ko": 0 }, "maxResponseTime": { "total": 77, "ok": 77, "ko": 0 }, "meanResponseTime": { "total": 74, "ok": 74, "ko": 0 }, "standardDeviation": { "total": 4, "ok": 4, "ko": 0 }, "percentiles1": { "total": 74, "ok": 74, "ko": 0 }, "percentiles2": { "total": 75, "ok": 75, "ko": 0 }, "percentiles3": { "total": 77, "ok": 77, "ko": 0 }, "percentiles4": { "total": 77, "ok": 77, "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": 69, "ok": 69, "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": 68, "ok": 68, "ko": 0 }, "percentiles3": { "total": 69, "ok": 69, "ko": 0 }, "percentiles4": { "total": 69, "ok": 69, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "User AccessToken PKCE stage", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "maxResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "meanResponseTime": { "total": 65, "ok": 65, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 65, "ok": 65, "ko": 0 }, "percentiles2": { "total": 65, "ok": 65, "ko": 0 }, "percentiles3": { "total": 65, "ok": 65, "ko": 0 }, "percentiles4": { "total": 65, "ok": 65, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "UpdateManagedUser", "numberOfRequests": { "total": 5737229, "ok": 5737212, "ko": 17 }, "minResponseTime": { "total": 37, "ok": 66, "ko": 37 }, "maxResponseTime": { "total": 60000, "ok": 7513, "ko": 60000 }, "meanResponseTime": { "total": 122, "ok": 122, "ko": 6499 }, "standardDeviation": { "total": 67, "ok": 62, "ko": 14106 }, "percentiles1": { "total": 109, "ok": 109, "ko": 41 }, "percentiles2": { "total": 128, "ok": 128, "ko": 10000 }, "percentiles3": { "total": 189, "ok": 189, "ko": 20030 }, "percentiles4": { "total": 362, "ok": 362, "ko": 52006 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 5731654, "percentage": 99.90282765425609 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 3518, "percentage": 0.06131880041741405 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 2040, "percentage": 0.03555723503454368 }, "group4": { "name": "failed", "htmlName": "failed", "count": 17, "percentage": 0.00029631029195453063 }, "meanNumberOfRequestsPerSecond": { "total": 1593.6747222222223, "ok": 1593.67, "ko": 0.004722222222222222 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 1594, "avg_response_time": 122, "max_response_time": 7513, "min_response_time": 63, "num_requests": 5737239, "num_requests_pass": 5737222, "num_requests_percent_pass": 100.0 }, "options": "; testMode:open; testCase:idm.ManagedUsersUpdate; duration:3600; throughput:1600", "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": 69, "total": 69 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 66, "total": 66 }, "minResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 66, "total": 66 }, "percentiles2": { "ko": 0, "ok": 68, "total": 68 }, "percentiles3": { "ko": 0, "ok": 69, "total": 69 }, "percentiles4": { "ko": 0, "ok": 69, "total": 69 }, "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": 131, "total": 131 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 121, "total": 121 }, "minResponseTime": { "ko": 0, "ok": 111, "total": 111 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 121, "total": 121 }, "percentiles2": { "ko": 0, "ok": 126, "total": 126 }, "percentiles3": { "ko": 0, "ok": 130, "total": 130 }, "percentiles4": { "ko": 0, "ok": 131, "total": 131 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 10, "total": 10 } }, { "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": 77, "total": 77 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 74, "total": 74 }, "minResponseTime": { "ko": 0, "ok": 70, "total": 70 }, "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": 77, "total": 77 }, "percentiles4": { "ko": 0, "ok": 77, "total": 77 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 4, "total": 4 } }, { "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": 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": 68, "total": 68 }, "percentiles3": { "ko": 0, "ok": 69, "total": 69 }, "percentiles4": { "ko": 0, "ok": 69, "total": 69 }, "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": 65, "total": 65 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 65, "total": 65 }, "minResponseTime": { "ko": 0, "ok": 65, "total": 65 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 65, "total": 65 }, "percentiles2": { "ko": 0, "ok": 65, "total": 65 }, "percentiles3": { "ko": 0, "ok": 65, "total": 65 }, "percentiles4": { "ko": 0, "ok": 65, "total": 65 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 5731654, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.90282765425609 }, "group2": { "count": 3518, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.06131880041741405 }, "group3": { "count": 2040, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.03555723503454368 }, "group4": { "count": 17, "htmlName": "failed", "name": "failed", "percentage": 0.00029631029195453063 }, "maxResponseTime": { "ko": 60000, "ok": 7513, "total": 60000 }, "meanNumberOfRequestsPerSecond": { "ko": 0.004722222222222222, "ok": 1593.67, "total": 1593.6747222222223 }, "meanResponseTime": { "ko": 6499, "ok": 122, "total": 122 }, "minResponseTime": { "ko": 37, "ok": 66, "total": 37 }, "name": "UpdateManagedUser", "numberOfRequests": { "ko": 17, "ok": 5737212, "total": 5737229 }, "percentiles1": { "ko": 41, "ok": 109, "total": 109 }, "percentiles2": { "ko": 10000, "ok": 128, "total": 128 }, "percentiles3": { "ko": 20030, "ok": 189, "total": 189 }, "percentiles4": { "ko": 52006, "ok": 362, "total": 362 }, "spikes": [], "standardDeviation": { "ko": 14106, "ok": 62, "total": 67 } } ], "global": { "group1": { "count": 5731664, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.90282782362736 }, "group2": { "count": 3518, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.06131869353882591 }, "group3": { "count": 2040, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.03555717305832998 }, "group4": { "count": 17, "htmlName": "failed", "name": "failed", "percentage": 0.00029630977548608314 }, "maxResponseTime": { "ko": 60000, "ok": 7513, "total": 60000 }, "meanNumberOfRequestsPerSecond": { "ko": 0.004722222222222222, "ok": 1593.6727777777778, "total": 1593.6775 }, "meanResponseTime": { "ko": 6499, "ok": 122, "total": 122 }, "minResponseTime": { "ko": 37, "ok": 63, "total": 37 }, "name": "All Requests", "numberOfRequests": { "ko": 17, "ok": 5737222, "total": 5737239 }, "percentiles1": { "ko": 41, "ok": 109, "total": 109 }, "percentiles2": { "ko": 10000, "ok": 128, "total": 128 }, "percentiles3": { "ko": 20030, "ok": 189, "total": 189 }, "percentiles4": { "ko": 52006, "ok": 362, "total": 362 }, "standardDeviation": { "ko": 14106, "ok": 62, "total": 67 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 16:04:39] Reporting-update-1600 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 16:04:39] Reporting-update-1600 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/15_Reporting-update-1600/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 16:04:39] Reporting-update-1600 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped