--Task-- name: Reporting-update-1700 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'] preceding_task: None options: {'task_name': 'update-1700'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 16:04:39 - INFO: Timeout for this Task has changed to 5h 30m (19800 seconds) 2025-03-28 16:04:39 - 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-1700/stdout.txt Pod stderr file : /results/orders/Reporting-update-1700/stderr.txt Pod log : /results/orders/Reporting-update-1700 ________________________________________________________________________________ [2025-03-28 16:04:39] Reporting-update-1700 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/16_Reporting-update-1700/order.json { "name": "Reporting-update-1700", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/update-1700\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"update-1700.tar.gz\"", "out-dir": "/results/orders/Reporting-update-1700" } ________________________________________________________________________________ [2025-03-28 16:04:39] Reporting-update-1700 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 16:04:39,436 INFO 2025-03-28 16:04:39,436 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:04:39,436 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 16:04:39,650 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:04:39,650 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:39,651 DEBUG --- stdout --- 2025-03-28 16:04:39,651 DEBUG WAITING 2025-03-28 16:04:39,651 DEBUG --- stderr --- 2025-03-28 16:04:39,651 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:04:39] Reporting-update-1700 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 16:04:39,651 INFO 2025-03-28 16:04:39,651 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:39,651 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:04:39,929 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:04:39,929 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:39,929 DEBUG --- stdout --- 2025-03-28 16:04:39,929 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:04:39,929 DEBUG --- stderr --- 2025-03-28 16:04:39,929 DEBUG 2025-03-28 16:04:39,930 INFO 2025-03-28 16:04:39,930 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/16_Reporting-update-1700/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 16:04:39,930 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:04:41,430 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:04:41,430 DEBUG --- stdout --- 2025-03-28 16:04:41,430 DEBUG 2025-03-28 16:04:41,431 DEBUG --- stderr --- 2025-03-28 16:04:41,431 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 16:04:41] Reporting-update-1700 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 16:04:46,431 INFO 2025-03-28 16:04:46,431 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 16:04:46,431 INFO [loop_until]: (max_time=19800, interval=5, expected_rc=[0] 2025-03-28 16:04:46,649 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:04:51,865 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:04:57,080 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:02,294 INFO [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:07,509 INFO [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:12,724 INFO [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:17,992 INFO [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:23,206 INFO [loop_until]: Function succeeded after 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:28,421 INFO [loop_until]: Function succeeded after 41s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:33,636 INFO [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:38,851 INFO [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:44,065 INFO [loop_until]: Function succeeded after 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:49,282 INFO [loop_until]: Function succeeded after 1m 02s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:54,551 INFO [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:05:59,766 INFO [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:06:04,981 INFO [loop_until]: Function succeeded after 1m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:06:10,196 INFO [loop_until]: Function succeeded after 1m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:06:15,411 INFO [loop_until]: Function succeeded after 1m 28s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:06:20,626 INFO [loop_until]: Function succeeded after 1m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:06:25,845 INFO [loop_until]: Function succeeded after 1m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:06:31,060 INFO [loop_until]: Function succeeded after 1m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:06:36,275 INFO [loop_until]: Function succeeded after 1m 49s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:06:41,490 INFO [loop_until]: Function succeeded after 1m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:06:46,704 INFO [loop_until]: Function succeeded after 2m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:06:51,919 INFO [loop_until]: Function succeeded after 2m 05s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:06:57,177 INFO [loop_until]: Function succeeded after 2m 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:02,392 INFO [loop_until]: Function succeeded after 2m 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:07,607 INFO [loop_until]: Function succeeded after 2m 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:12,822 INFO [loop_until]: Function succeeded after 2m 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:18,038 INFO [loop_until]: Function succeeded after 2m 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:23,253 INFO [loop_until]: Function succeeded after 2m 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:28,565 INFO [loop_until]: Function succeeded after 2m 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:33,779 INFO [loop_until]: Function succeeded after 2m 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:38,995 INFO [loop_until]: Function succeeded after 2m 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:44,305 INFO [loop_until]: Function succeeded after 2m 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:49,520 INFO [loop_until]: Function succeeded after 3m 03s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:54,735 INFO [loop_until]: Function succeeded after 3m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:07:59,951 INFO [loop_until]: Function succeeded after 3m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:08:05,235 INFO [loop_until]: Function succeeded after 3m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:08:10,449 INFO [loop_until]: Function succeeded after 3m 24s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:08:15,664 INFO [loop_until]: Function succeeded after 3m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:08:20,879 INFO [loop_until]: Function succeeded after 3m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:08:26,093 INFO [loop_until]: Function succeeded after 3m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:08:31,308 INFO [loop_until]: Function succeeded after 3m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:08:36,524 INFO [loop_until]: Function succeeded after 3m 50s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:08:41,792 INFO [loop_until]: Function succeeded after 3m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:08:47,007 INFO [loop_until]: Function succeeded after 4m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 16:08:52,222 INFO [loop_until]: Function succeeded after 4m 05s (rc=0) - expected pattern found 2025-03-28 16:08:52,222 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:08:52,223 DEBUG --- stdout --- 2025-03-28 16:08:52,223 DEBUG WAITING 2025-03-28 16:08:52,223 DEBUG --- stderr --- 2025-03-28 16:08:52,223 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:08:52] Reporting-update-1700 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 16:08:52,223 INFO 2025-03-28 16:08:52,223 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:08:52,223 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:08:52,522 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:08:52,522 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:08:52,522 DEBUG --- stdout --- 2025-03-28 16:08:52,522 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:08:52,522 DEBUG --- stderr --- 2025-03-28 16:08:52,522 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:08:52,523 INFO 2025-03-28 16:08:52,523 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-1700/update-1700.tar.gz" ]; then echo "/results/gatling/update-1700/update-1700.tar.gz file found"; exit 0; else echo "/results/gatling/update-1700/update-1700.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/update-1700/update-1700.tar.gz file found" 2025-03-28 16:08:52,523 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:08:53,373 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:08:53,373 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:08:53,373 DEBUG --- stdout --- 2025-03-28 16:08:53,373 DEBUG /results/gatling/update-1700/update-1700.tar.gz file found 2025-03-28 16:08:53,373 DEBUG --- stderr --- 2025-03-28 16:08:53,373 DEBUG 2025-03-28 16:08:53,373 INFO 2025-03-28 16:08:53,374 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-1700/update-1700.tar.gz 2025-03-28 16:08:54,181 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:08:54,181 DEBUG --- stdout --- 2025-03-28 16:08:54,181 DEBUG 320 /results/gatling/update-1700/update-1700.tar.gz 2025-03-28 16:08:54,181 DEBUG --- stderr --- 2025-03-28 16:08:54,181 DEBUG 2025-03-28 16:08:54,181 INFO - Download file (size 320k) source : /results/gatling/update-1700/update-1700.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-1700.tar.gz 2025-03-28 16:08:54,182 INFO 2025-03-28 16:08:54,182 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/update-1700/update-1700.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-1700.tar.gz 2025-03-28 16:08:54,182 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:08:55,181 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:08:55,181 DEBUG --- stdout --- 2025-03-28 16:08:55,181 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:08:55,181 DEBUG --- stderr --- 2025-03-28 16:08:55,181 DEBUG ________________________________________________________________________________ [2025-03-28 16:08:55] Reporting-update-1700 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 16:08:55,182 INFO 2025-03-28 16:08:55,182 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/update-1700.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/gatling 2025-03-28 16:08:55,197 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:08:55,197 DEBUG --- stdout --- 2025-03-28 16:08:55,197 DEBUG 2025-03-28 16:08:55,197 DEBUG --- stderr --- 2025-03-28 16:08:55,197 DEBUG 2025-03-28 16:08:55,197 INFO ________________________________________________________________________________ [2025-03-28 16:08:55] Reporting-update-1700 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 16:08:55,197 INFO 2025-03-28 16:08:55,197 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:08:55,197 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:08:55,523 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:08:55,523 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:08:55,523 DEBUG --- stdout --- 2025-03-28 16:08:55,523 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:08:55,523 DEBUG --- stderr --- 2025-03-28 16:08:55,523 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:08:55,524 INFO 2025-03-28 16:08:55,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-1700/rc.txt" ]; then echo "/results/orders/Reporting-update-1700/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-update-1700/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-1700/rc.txt file found" 2025-03-28 16:08:55,524 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:08:56,321 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:08:56,321 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:08:56,321 DEBUG --- stdout --- 2025-03-28 16:08:56,321 DEBUG /results/orders/Reporting-update-1700/rc.txt file found 2025-03-28 16:08:56,321 DEBUG --- stderr --- 2025-03-28 16:08:56,321 DEBUG 2025-03-28 16:08:56,321 INFO 2025-03-28 16:08:56,322 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/Reporting-update-1700/rc.txt 2025-03-28 16:08:57,125 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:08:57,125 DEBUG --- stdout --- 2025-03-28 16:08:57,125 DEBUG 4 /results/orders/Reporting-update-1700/rc.txt 2025-03-28 16:08:57,125 DEBUG --- stderr --- 2025-03-28 16:08:57,125 DEBUG 2025-03-28 16:08:57,125 INFO - Download file (size 4k) source : /results/orders/Reporting-update-1700/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/16_Reporting-update-1700/rc.txt 2025-03-28 16:08:57,126 INFO 2025-03-28 16:08:57,126 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-1700/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/16_Reporting-update-1700/rc.txt 2025-03-28 16:08:57,126 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:08:57,843 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:08:57,843 DEBUG --- stdout --- 2025-03-28 16:08:57,843 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:08:57,843 DEBUG --- stderr --- 2025-03-28 16:08:57,843 DEBUG 2025-03-28 16:08:57,843 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/16_Reporting-update-1700/rc.txt is small (1 lines), printing all lines: 2025-03-28 16:08:57,843 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 16:08:57] Reporting-update-1700 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:08:58] Reporting-update-1700 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 16:08:58,081 INFO 2025-03-28 16:08:58,081 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:08:58,081 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:08:58,358 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:08:58,358 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:08:58,358 DEBUG --- stdout --- 2025-03-28 16:08:58,358 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:08:58,358 DEBUG --- stderr --- 2025-03-28 16:08:58,358 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:08:58,359 INFO 2025-03-28 16:08:58,359 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-1700/stdout.txt" ]; then echo "/results/orders/Reporting-update-1700/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-update-1700/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-1700/stdout.txt file found" 2025-03-28 16:08:58,359 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:08:59,160 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:08:59,160 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:08:59,160 DEBUG --- stdout --- 2025-03-28 16:08:59,160 DEBUG /results/orders/Reporting-update-1700/stdout.txt file found 2025-03-28 16:08:59,160 DEBUG --- stderr --- 2025-03-28 16:08:59,160 DEBUG 2025-03-28 16:08:59,160 INFO 2025-03-28 16:08:59,160 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-1700/stdout.txt 2025-03-28 16:08:59,966 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:08:59,966 DEBUG --- stdout --- 2025-03-28 16:08:59,966 DEBUG 8 /results/orders/Reporting-update-1700/stdout.txt 2025-03-28 16:08:59,966 DEBUG --- stderr --- 2025-03-28 16:08:59,966 DEBUG 2025-03-28 16:08:59,966 INFO - Download file (size 8k) source : /results/orders/Reporting-update-1700/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/16_Reporting-update-1700/stdout.txt 2025-03-28 16:08:59,967 INFO 2025-03-28 16:08:59,967 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-1700/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/16_Reporting-update-1700/stdout.txt 2025-03-28 16:08:59,967 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:09:00,734 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:09:00,734 DEBUG --- stdout --- 2025-03-28 16:09:00,734 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:09:00,734 DEBUG --- stderr --- 2025-03-28 16:09:00,734 DEBUG 2025-03-28 16:09:00,734 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/16_Reporting-update-1700/stdout.txt is small (95 lines), printing all lines: 2025-03-28 16:09:00,734 INFO ----- output ----- 2025-03-28 16:04:42,272 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp0hgabrbw 2025-03-28 16:04:42,274 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/update-1700/loadtest-20250328094550839" -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/tmp0hgabrbw/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/tmp0hgabrbw/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:04:44,107 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 16:04:44,108 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 16:04:44,108 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 16:04:44,109 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 16:04:44,115 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 16:04:44,115 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 16:04:44,123 |-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:04:44,123 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 16:04:44,123 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 16:04:44,123 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 16:04:44,126 |-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:04:44,131 |-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:04:44,175 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 16:04:44,175 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 16:04:44,214 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 16:04:44,214 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 16:04:44,221 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 16:04:44,242 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 16:04:44,242 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 16:04:44,242 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 16:04:44,243 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 16:04:44,243 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 120 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY Parsing log file(s)... Parsing log file(s) done in 245s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 6100945 (OK=6100920 KO=25 ) > min response time 35 (OK=59 KO=35 ) > max response time 60000 (OK=9386 KO=60000 ) > mean response time 210 (OK=210 KO=4448 ) > std deviation 315 (OK=314 KO=12011 ) > response time 50th percentile 143 (OK=143 KO=41 ) > response time 75th percentile 205 (OK=205 KO=205 ) > response time 95th percentile 391 (OK=391 KO=10034 ) > response time 99th percentile 1623 (OK=1623 KO=48010 ) > mean requests/sec 1694.71 (OK=1694.7 KO=0.01 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 5979386 ( 98.01%) > 800 ms <= t < 1200 ms 36264 ( 0.59%) > t >= 1200 ms 85270 ( 1.4%) > failed 25 ( 0%) ---- Errors -------------------------------------------------------------------- > status.find.in(200), but actually found 502 17 (68.00%) > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 3 (12.00%) r 10000ms > status.find.in(200), but actually found 503 2 ( 8.00%) > j.i.IOException: Premature close 2 ( 8.00%) > Request timeout to openam-perf-200m.forgeblocks.com/34.107.203 1 ( 4.00%) .37:443 after 60000 ms ================================================================================ Reports generated, please open the following file: file:///results/gatling/update-1700/loadtest-20250328094550839/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 04:07 min [INFO] Finished at: 2025-03-28T16:08:50Z [INFO] ------------------------------------------------------------------------ 2025-03-28 16:08:50,929 INFO Gatling simulation run ended successfully 2025-03-28 16:08:50,930 INFO Creating archive update-1700.tar.gz with report files 2025-03-28 16:08:51,036 INFO Archive /results/gatling/update-1700/update-1700.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 16:09:00] Reporting-update-1700 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 16:09:00] Reporting-update-1700 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 16:09:00,735 INFO 2025-03-28 16:09:00,735 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:09:00,735 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 16:09:01,015 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 16:09:01,015 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:09:01,015 DEBUG --- stdout --- 2025-03-28 16:09:01,015 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 16:09:01,015 DEBUG --- stderr --- 2025-03-28 16:09:01,015 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 16:09:01,016 INFO 2025-03-28 16:09:01,016 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-1700/stderr.txt" ]; then echo "/results/orders/Reporting-update-1700/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-update-1700/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-update-1700/stderr.txt file found" 2025-03-28 16:09:01,016 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 16:09:01,826 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 16:09:01,826 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:09:01,826 DEBUG --- stdout --- 2025-03-28 16:09:01,826 DEBUG /results/orders/Reporting-update-1700/stderr.txt file found 2025-03-28 16:09:01,826 DEBUG --- stderr --- 2025-03-28 16:09:01,826 DEBUG 2025-03-28 16:09:01,826 INFO 2025-03-28 16:09:01,826 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-1700/stderr.txt 2025-03-28 16:09:02,631 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 16:09:02,632 DEBUG --- stdout --- 2025-03-28 16:09:02,632 DEBUG 0 /results/orders/Reporting-update-1700/stderr.txt 2025-03-28 16:09:02,632 DEBUG --- stderr --- 2025-03-28 16:09:02,632 DEBUG 2025-03-28 16:09:02,632 INFO - Download file (size 0k) source : /results/orders/Reporting-update-1700/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/16_Reporting-update-1700/stderr.txt 2025-03-28 16:09:02,632 INFO 2025-03-28 16:09:02,632 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-1700/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/16_Reporting-update-1700/stderr.txt 2025-03-28 16:09:02,633 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 16:09:03,352 INFO [loop_until]: OK (rc = 0) 2025-03-28 16:09:03,352 DEBUG --- stdout --- 2025-03-28 16:09:03,352 DEBUG tar: Removing leading `/' from member names 2025-03-28 16:09:03,352 DEBUG --- stderr --- 2025-03-28 16:09:03,352 DEBUG 2025-03-28 16:09:03,353 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/16_Reporting-update-1700/stderr.txt is small (0 lines), printing all lines: 2025-03-28 16:09:03,353 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 16:09:03] Reporting-update-1700 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 16:09:03] Reporting-update-1700 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/05_update-1700/gatling/req_updatemanagedus--1469575327.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 16:09:03] Reporting-update-1700 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 6100945, "ok": 6100920, "ko": 25 }, "minResponseTime": { "total": 35, "ok": 59, "ko": 35 }, "maxResponseTime": { "total": 60000, "ok": 9386, "ko": 60000 }, "meanResponseTime": { "total": 210, "ok": 210, "ko": 4448 }, "standardDeviation": { "total": 315, "ok": 314, "ko": 12011 }, "percentiles1": { "total": 143, "ok": 143, "ko": 41 }, "percentiles2": { "total": 205, "ok": 205, "ko": 205 }, "percentiles3": { "total": 391, "ok": 391, "ko": 10034 }, "percentiles4": { "total": 1623, "ok": 1623, "ko": 48010 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 5979386, "percentage": 98.00753817646283 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 36264, "percentage": 0.5943997200433703 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 85270, "percentage": 1.3976523309093918 }, "group4": { "name": "failed", "htmlName": "failed", "count": 25, "percentage": 0.0004097725844111035 }, "meanNumberOfRequestsPerSecond": { "total": 1694.7069444444444, "ok": 1694.7, "ko": 0.006944444444444444 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 67, "ok": 67, "ko": 0 }, "maxResponseTime": { "total": 70, "ok": 70, "ko": 0 }, "meanResponseTime": { "total": 69, "ok": 69, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 69, "ok": 69, "ko": 0 }, "percentiles2": { "total": 69, "ok": 69, "ko": 0 }, "percentiles3": { "total": 70, "ok": 70, "ko": 0 }, "percentiles4": { "total": 70, "ok": 70, "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": 100, "ok": 100, "ko": 0 }, "maxResponseTime": { "total": 111, "ok": 111, "ko": 0 }, "meanResponseTime": { "total": 106, "ok": 106, "ko": 0 }, "standardDeviation": { "total": 6, "ok": 6, "ko": 0 }, "percentiles1": { "total": 106, "ok": 106, "ko": 0 }, "percentiles2": { "total": 108, "ok": 108, "ko": 0 }, "percentiles3": { "total": 110, "ok": 110, "ko": 0 }, "percentiles4": { "total": 111, "ok": 111, "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": 67, "ok": 67, "ko": 0 }, "maxResponseTime": { "total": 70, "ok": 70, "ko": 0 }, "meanResponseTime": { "total": 69, "ok": 69, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 69, "ok": 69, "ko": 0 }, "percentiles2": { "total": 69, "ok": 69, "ko": 0 }, "percentiles3": { "total": 70, "ok": 70, "ko": 0 }, "percentiles4": { "total": 70, "ok": 70, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "User Authorize PKCE stage", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 59, "ok": 59, "ko": 0 }, "maxResponseTime": { "total": 96, "ok": 96, "ko": 0 }, "meanResponseTime": { "total": 78, "ok": 78, "ko": 0 }, "standardDeviation": { "total": 19, "ok": 19, "ko": 0 }, "percentiles1": { "total": 78, "ok": 78, "ko": 0 }, "percentiles2": { "total": 87, "ok": 87, "ko": 0 }, "percentiles3": { "total": 94, "ok": 94, "ko": 0 }, "percentiles4": { "total": 96, "ok": 96, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 2, "percentage": 100.0 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0.0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0.0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0.0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005555555555555556, "ok": 0.0005555555555555556, "ko": 0 }, "spikes": [] }, { "name": "User AccessToken PKCE stage", "numberOfRequests": { "total": 2, "ok": 2, "ko": 0 }, "minResponseTime": { "total": 59, "ok": 59, "ko": 0 }, "maxResponseTime": { "total": 67, "ok": 67, "ko": 0 }, "meanResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "standardDeviation": { "total": 4, "ok": 4, "ko": 0 }, "percentiles1": { "total": 63, "ok": 63, "ko": 0 }, "percentiles2": { "total": 65, "ok": 65, "ko": 0 }, "percentiles3": { "total": 67, "ok": 67, "ko": 0 }, "percentiles4": { "total": 67, "ok": 67, "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": 6100935, "ok": 6100910, "ko": 25 }, "minResponseTime": { "total": 35, "ok": 67, "ko": 35 }, "maxResponseTime": { "total": 60000, "ok": 9386, "ko": 60000 }, "meanResponseTime": { "total": 210, "ok": 210, "ko": 4448 }, "standardDeviation": { "total": 315, "ok": 314, "ko": 12011 }, "percentiles1": { "total": 143, "ok": 143, "ko": 41 }, "percentiles2": { "total": 205, "ok": 205, "ko": 205 }, "percentiles3": { "total": 391, "ok": 391, "ko": 10034 }, "percentiles4": { "total": 1623, "ok": 1623, "ko": 48010 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 5979376, "percentage": 98.00753491063256 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 36264, "percentage": 0.5944006943198051 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 85270, "percentage": 1.397654621791578 }, "group4": { "name": "failed", "htmlName": "failed", "count": 25, "percentage": 0.0004097732560664882 }, "meanNumberOfRequestsPerSecond": { "total": 1694.7041666666667, "ok": 1694.6972222222223, "ko": 0.006944444444444444 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 1695, "avg_response_time": 210, "max_response_time": 9386, "min_response_time": 59, "num_requests": 6100945, "num_requests_pass": 6100920, "num_requests_percent_pass": 100.0 }, "options": "; testMode:open; testCase:idm.ManagedUsersUpdate; duration:3600; throughput:1700", "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": 70, "total": 70 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 69, "total": 69 }, "minResponseTime": { "ko": 0, "ok": 67, "total": 67 }, "name": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 69, "total": 69 }, "percentiles2": { "ko": 0, "ok": 69, "total": 69 }, "percentiles3": { "ko": 0, "ok": 70, "total": 70 }, "percentiles4": { "ko": 0, "ok": 70, "total": 70 }, "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": 111, "total": 111 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 106, "total": 106 }, "minResponseTime": { "ko": 0, "ok": 100, "total": 100 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 106, "total": 106 }, "percentiles2": { "ko": 0, "ok": 108, "total": 108 }, "percentiles3": { "ko": 0, "ok": 110, "total": 110 }, "percentiles4": { "ko": 0, "ok": 111, "total": 111 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 6, "total": 6 } }, { "group1": { "count": 2, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 70, "total": 70 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 69, "total": 69 }, "minResponseTime": { "ko": 0, "ok": 67, "total": 67 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 69, "total": 69 }, "percentiles2": { "ko": 0, "ok": 69, "total": 69 }, "percentiles3": { "ko": 0, "ok": 70, "total": 70 }, "percentiles4": { "ko": 0, "ok": 70, "total": 70 }, "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": 96, "total": 96 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 78, "total": 78 }, "minResponseTime": { "ko": 0, "ok": 59, "total": 59 }, "name": "User Authorize PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 78, "total": 78 }, "percentiles2": { "ko": 0, "ok": 87, "total": 87 }, "percentiles3": { "ko": 0, "ok": 94, "total": 94 }, "percentiles4": { "ko": 0, "ok": 96, "total": 96 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 19, "total": 19 } }, { "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": 67, "total": 67 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "minResponseTime": { "ko": 0, "ok": 59, "total": 59 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 2, "total": 2 }, "percentiles1": { "ko": 0, "ok": 63, "total": 63 }, "percentiles2": { "ko": 0, "ok": 65, "total": 65 }, "percentiles3": { "ko": 0, "ok": 67, "total": 67 }, "percentiles4": { "ko": 0, "ok": 67, "total": 67 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 4, "total": 4 } }, { "group1": { "count": 5979376, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 98.00753491063256 }, "group2": { "count": 36264, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.5944006943198051 }, "group3": { "count": 85270, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 1.397654621791578 }, "group4": { "count": 25, "htmlName": "failed", "name": "failed", "percentage": 0.0004097732560664882 }, "maxResponseTime": { "ko": 60000, "ok": 9386, "total": 60000 }, "meanNumberOfRequestsPerSecond": { "ko": 0.006944444444444444, "ok": 1694.6972222222223, "total": 1694.7041666666667 }, "meanResponseTime": { "ko": 4448, "ok": 210, "total": 210 }, "minResponseTime": { "ko": 35, "ok": 67, "total": 35 }, "name": "UpdateManagedUser", "numberOfRequests": { "ko": 25, "ok": 6100910, "total": 6100935 }, "percentiles1": { "ko": 41, "ok": 143, "total": 143 }, "percentiles2": { "ko": 205, "ok": 205, "total": 205 }, "percentiles3": { "ko": 10034, "ok": 391, "total": 391 }, "percentiles4": { "ko": 48010, "ok": 1623, "total": 1623 }, "spikes": [], "standardDeviation": { "ko": 12011, "ok": 314, "total": 315 } } ], "global": { "group1": { "count": 5979386, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 98.00753817646283 }, "group2": { "count": 36264, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.5943997200433703 }, "group3": { "count": 85270, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 1.3976523309093918 }, "group4": { "count": 25, "htmlName": "failed", "name": "failed", "percentage": 0.0004097725844111035 }, "maxResponseTime": { "ko": 60000, "ok": 9386, "total": 60000 }, "meanNumberOfRequestsPerSecond": { "ko": 0.006944444444444444, "ok": 1694.7, "total": 1694.7069444444444 }, "meanResponseTime": { "ko": 4448, "ok": 210, "total": 210 }, "minResponseTime": { "ko": 35, "ok": 59, "total": 35 }, "name": "All Requests", "numberOfRequests": { "ko": 25, "ok": 6100920, "total": 6100945 }, "percentiles1": { "ko": 41, "ok": 143, "total": 143 }, "percentiles2": { "ko": 205, "ok": 205, "total": 205 }, "percentiles3": { "ko": 10034, "ok": 391, "total": 391 }, "percentiles4": { "ko": 48010, "ok": 1623, "total": 1623 }, "standardDeviation": { "ko": 12011, "ok": 314, "total": 315 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 16:09:03] Reporting-update-1700 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 16:09:03] Reporting-update-1700 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/16_Reporting-update-1700/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 16:09:03] Reporting-update-1700 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped