--Task-- name: Reporting-mixedquery-10000 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-mixedquery-1000', 'Logs-parse-mixedquery-2000', 'Logs-parse-mixedquery-4000', 'Logs-parse-mixedquery-6000', 'Logs-parse-mixedquery-8000', 'Logs-parse-mixedquery-10000', 'Reporting-mixedquery-1000', 'Reporting-mixedquery-2000', 'Reporting-mixedquery-4000', 'Reporting-mixedquery-6000', 'Reporting-mixedquery-8000'] preceding_task: None options: {'task_name': 'mixedquery-10000'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock source (overseer-0) is a component 2025-03-28 01:55:01 - INFO: Timeout for this Task has changed to 3h 30m (12600 seconds) 2025-03-28 01:55:01 - 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-mixedquery-10000/stdout.txt Pod stderr file : /results/orders/Reporting-mixedquery-10000/stderr.txt Pod log : /results/orders/Reporting-mixedquery-10000 ________________________________________________________________________________ [2025-03-28 01:55:01] Reporting-mixedquery-10000 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/13_Reporting-mixedquery-10000/order.json { "name": "Reporting-mixedquery-10000", "command": " /lodestar/lodestar.py perf run-gatling --reports-only=\"/results/gatling/mixedquery-10000\" --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --archive-name=\"mixedquery-10000.tar.gz\"", "out-dir": "/results/orders/Reporting-mixedquery-10000" } ________________________________________________________________________________ [2025-03-28 01:55:01] Reporting-mixedquery-10000 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2025-03-28 01:55:01,714 INFO 2025-03-28 01:55:01,714 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 01:55:01,714 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2025-03-28 01:55:01,928 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 01:55:01,928 INFO [loop_until]: OK (rc = 0) 2025-03-28 01:55:01,928 DEBUG --- stdout --- 2025-03-28 01:55:01,929 DEBUG WAITING 2025-03-28 01:55:01,929 DEBUG --- stderr --- 2025-03-28 01:55:01,929 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 01:55:01] Reporting-mixedquery-10000 step3 : Send order to overseer ________________________________________________________________________________ 2025-03-28 01:55:01,929 INFO 2025-03-28 01:55:01,929 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 01:55:01,929 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 01:55:02,210 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 01:55:02,210 INFO [loop_until]: OK (rc = 0) 2025-03-28 01:55:02,210 DEBUG --- stdout --- 2025-03-28 01:55:02,210 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 01:55:02,210 DEBUG --- stderr --- 2025-03-28 01:55:02,210 DEBUG 2025-03-28 01:55:02,210 INFO 2025-03-28 01:55:02,210 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/13_Reporting-mixedquery-10000/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 01:55:02,210 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 01:55:03,591 INFO [loop_until]: OK (rc = 0) 2025-03-28 01:55:03,591 DEBUG --- stdout --- 2025-03-28 01:55:03,591 DEBUG 2025-03-28 01:55:03,591 DEBUG --- stderr --- 2025-03-28 01:55:03,591 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2025-03-28 01:55:03] Reporting-mixedquery-10000 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2025-03-28 01:55:08,591 INFO 2025-03-28 01:55:08,591 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 01:55:08,591 INFO [loop_until]: (max_time=12600, interval=5, expected_rc=[0] 2025-03-28 01:55:08,807 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:55:14,022 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:55:19,238 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:55:24,453 INFO [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:55:29,720 INFO [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:55:34,936 INFO [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:55:40,151 INFO [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:55:45,367 INFO [loop_until]: Function succeeded after 36s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:55:50,582 INFO [loop_until]: Function succeeded after 41s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:55:55,852 INFO [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:01,112 INFO [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:06,327 INFO [loop_until]: Function succeeded after 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:11,543 INFO [loop_until]: Function succeeded after 1m 02s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:16,758 INFO [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:21,973 INFO [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:27,188 INFO [loop_until]: Function succeeded after 1m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:32,405 INFO [loop_until]: Function succeeded after 1m 23s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:37,714 INFO [loop_until]: Function succeeded after 1m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:42,928 INFO [loop_until]: Function succeeded after 1m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:48,145 INFO [loop_until]: Function succeeded after 1m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:53,359 INFO [loop_until]: Function succeeded after 1m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:56:58,574 INFO [loop_until]: Function succeeded after 1m 49s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:57:03,789 INFO [loop_until]: Function succeeded after 1m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:57:09,047 INFO [loop_until]: Function succeeded after 2m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:57:14,265 INFO [loop_until]: Function succeeded after 2m 05s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:57:19,480 INFO [loop_until]: Function succeeded after 2m 10s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:57:24,694 INFO [loop_until]: Function succeeded after 2m 16s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:57:29,909 INFO [loop_until]: Function succeeded after 2m 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:57:35,125 INFO [loop_until]: Function succeeded after 2m 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:57:40,393 INFO [loop_until]: Function succeeded after 2m 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:57:45,608 INFO [loop_until]: Function succeeded after 2m 37s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:57:50,823 INFO [loop_until]: Function succeeded after 2m 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:57:56,039 INFO [loop_until]: Function succeeded after 2m 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:01,253 INFO [loop_until]: Function succeeded after 2m 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:06,468 INFO [loop_until]: Function succeeded after 2m 57s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:11,779 INFO [loop_until]: Function succeeded after 3m 03s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:16,996 INFO [loop_until]: Function succeeded after 3m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:22,211 INFO [loop_until]: Function succeeded after 3m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:27,426 INFO [loop_until]: Function succeeded after 3m 18s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:32,641 INFO [loop_until]: Function succeeded after 3m 24s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:37,856 INFO [loop_until]: Function succeeded after 3m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:43,073 INFO [loop_until]: Function succeeded after 3m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:48,356 INFO [loop_until]: Function succeeded after 3m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:53,572 INFO [loop_until]: Function succeeded after 3m 44s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:58:58,786 INFO [loop_until]: Function succeeded after 3m 50s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:59:04,001 INFO [loop_until]: Function succeeded after 3m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:59:09,218 INFO [loop_until]: Function succeeded after 4m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:59:14,433 INFO [loop_until]: Function succeeded after 4m 05s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:59:19,702 INFO [loop_until]: Function succeeded after 4m 11s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:59:24,917 INFO [loop_until]: Function succeeded after 4m 16s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:59:30,132 INFO [loop_until]: Function succeeded after 4m 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:59:35,348 INFO [loop_until]: Function succeeded after 4m 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:59:40,563 INFO [loop_until]: Function succeeded after 4m 31s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:59:45,778 INFO [loop_until]: Function succeeded after 4m 37s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:59:51,089 INFO [loop_until]: Function succeeded after 4m 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 01:59:56,304 INFO [loop_until]: Function succeeded after 4m 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:01,519 INFO [loop_until]: Function succeeded after 4m 52s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:06,734 INFO [loop_until]: Function succeeded after 4m 58s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:11,950 INFO [loop_until]: Function succeeded after 5m 03s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:17,165 INFO [loop_until]: Function succeeded after 5m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:22,449 INFO [loop_until]: Function succeeded after 5m 13s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:27,664 INFO [loop_until]: Function succeeded after 5m 19s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:32,879 INFO [loop_until]: Function succeeded after 5m 24s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:38,099 INFO [loop_until]: Function succeeded after 5m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:43,314 INFO [loop_until]: Function succeeded after 5m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:48,529 INFO [loop_until]: Function succeeded after 5m 39s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:53,747 INFO [loop_until]: Function succeeded after 5m 45s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:00:59,005 INFO [loop_until]: Function succeeded after 5m 50s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:01:04,289 INFO [loop_until]: Function succeeded after 5m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:01:09,504 INFO [loop_until]: Function succeeded after 6m (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:01:14,719 INFO [loop_until]: Function succeeded after 6m 06s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:01:19,934 INFO [loop_until]: Function succeeded after 6m 11s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:01:25,150 INFO [loop_until]: Function succeeded after 6m 16s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:01:30,364 INFO [loop_until]: Function succeeded after 6m 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:01:35,581 INFO [loop_until]: Function succeeded after 6m 26s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:01:40,890 INFO [loop_until]: Function succeeded after 6m 32s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:01:46,105 INFO [loop_until]: Function succeeded after 6m 37s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:01:51,320 INFO [loop_until]: Function succeeded after 6m 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:01:56,536 INFO [loop_until]: Function succeeded after 6m 47s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:01,751 INFO [loop_until]: Function succeeded after 6m 53s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:06,966 INFO [loop_until]: Function succeeded after 6m 58s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:12,226 INFO [loop_until]: Function succeeded after 7m 03s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:17,440 INFO [loop_until]: Function succeeded after 7m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:22,656 INFO [loop_until]: Function succeeded after 7m 14s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:27,872 INFO [loop_until]: Function succeeded after 7m 19s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:33,087 INFO [loop_until]: Function succeeded after 7m 24s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:38,303 INFO [loop_until]: Function succeeded after 7m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:43,520 INFO [loop_until]: Function succeeded after 7m 34s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:48,803 INFO [loop_until]: Function succeeded after 7m 40s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:54,018 INFO [loop_until]: Function succeeded after 7m 45s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:02:59,234 INFO [loop_until]: Function succeeded after 7m 50s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:03:04,448 INFO [loop_until]: Function succeeded after 7m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:03:09,666 INFO [loop_until]: Function succeeded after 8m 01s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:03:14,881 INFO [loop_until]: Function succeeded after 8m 06s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:03:20,149 INFO [loop_until]: Function succeeded after 8m 11s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:03:25,364 INFO [loop_until]: Function succeeded after 8m 16s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:03:30,579 INFO [loop_until]: Function succeeded after 8m 21s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:03:35,794 INFO [loop_until]: Function succeeded after 8m 27s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:03:41,009 INFO [loop_until]: Function succeeded after 8m 32s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:03:46,224 INFO [loop_until]: Function succeeded after 8m 37s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:03:51,482 INFO [loop_until]: Function succeeded after 8m 42s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:03:56,697 INFO [loop_until]: Function succeeded after 8m 48s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:01,913 INFO [loop_until]: Function succeeded after 8m 53s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:07,128 INFO [loop_until]: Function succeeded after 8m 58s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:12,343 INFO [loop_until]: Function succeeded after 9m 03s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:17,558 INFO [loop_until]: Function succeeded after 9m 08s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:22,816 INFO [loop_until]: Function succeeded after 9m 14s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:28,031 INFO [loop_until]: Function succeeded after 9m 19s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:33,246 INFO [loop_until]: Function succeeded after 9m 24s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:38,461 INFO [loop_until]: Function succeeded after 9m 29s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:43,676 INFO [loop_until]: Function succeeded after 9m 35s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:48,891 INFO [loop_until]: Function succeeded after 9m 40s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:54,150 INFO [loop_until]: Function succeeded after 9m 45s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:04:59,365 INFO [loop_until]: Function succeeded after 9m 50s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:05:04,580 INFO [loop_until]: Function succeeded after 9m 55s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:05:09,795 INFO [loop_until]: Function succeeded after 10m 01s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:05:15,009 INFO [loop_until]: Function succeeded after 10m 06s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:05:20,224 INFO [loop_until]: Function succeeded after 10m 11s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:05:25,530 INFO [loop_until]: Function succeeded after 10m 16s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:05:30,750 INFO [loop_until]: Function succeeded after 10m 22s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:05:35,965 INFO [loop_until]: Function succeeded after 10m 27s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:05:41,179 INFO [loop_until]: Function succeeded after 10m 32s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:05:46,394 INFO [loop_until]: Function succeeded after 10m 37s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:05:51,610 INFO [loop_until]: Function succeeded after 10m 43s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:05:56,867 INFO [loop_until]: Function succeeded after 10m 48s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:02,126 INFO [loop_until]: Function succeeded after 10m 53s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:07,341 INFO [loop_until]: Function succeeded after 10m 58s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:12,556 INFO [loop_until]: Function succeeded after 11m 03s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:17,770 INFO [loop_until]: Function succeeded after 11m 09s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:22,985 INFO [loop_until]: Function succeeded after 11m 14s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:28,243 INFO [loop_until]: Function succeeded after 11m 19s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:33,458 INFO [loop_until]: Function succeeded after 11m 24s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:38,673 INFO [loop_until]: Function succeeded after 11m 30s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:43,888 INFO [loop_until]: Function succeeded after 11m 35s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:49,103 INFO [loop_until]: Function succeeded after 11m 40s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:54,317 INFO [loop_until]: Function succeeded after 11m 45s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:06:59,535 INFO [loop_until]: Function succeeded after 11m 50s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:07:04,749 INFO [loop_until]: Function succeeded after 11m 56s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:07:09,964 INFO [loop_until]: Function succeeded after 12m 01s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:07:15,180 INFO [loop_until]: Function succeeded after 12m 06s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:07:20,395 INFO [loop_until]: Function succeeded after 12m 11s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:07:25,610 INFO [loop_until]: Function succeeded after 12m 17s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:07:30,878 INFO [loop_until]: Function succeeded after 12m 22s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:07:36,093 INFO [loop_until]: Function succeeded after 12m 27s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:07:41,308 INFO [loop_until]: Function succeeded after 12m 32s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:07:46,523 INFO [loop_until]: Function succeeded after 12m 37s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:07:51,737 INFO [loop_until]: Function succeeded after 12m 43s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:07:56,952 INFO [loop_until]: Function succeeded after 12m 48s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:02,236 INFO [loop_until]: Function succeeded after 12m 53s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:07,451 INFO [loop_until]: Function succeeded after 12m 58s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:12,665 INFO [loop_until]: Function succeeded after 13m 04s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:17,880 INFO [loop_until]: Function succeeded after 13m 09s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:23,095 INFO [loop_until]: Function succeeded after 13m 14s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:28,310 INFO [loop_until]: Function succeeded after 13m 19s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:33,619 INFO [loop_until]: Function succeeded after 13m 25s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:38,834 INFO [loop_until]: Function succeeded after 13m 30s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:44,048 INFO [loop_until]: Function succeeded after 13m 35s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:49,263 INFO [loop_until]: Function succeeded after 13m 40s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:54,480 INFO [loop_until]: Function succeeded after 13m 45s (rc=0) - failed to find expected output: WAITING - retry 2025-03-28 02:08:59,695 INFO [loop_until]: Function succeeded after 13m 51s (rc=0) - expected pattern found 2025-03-28 02:08:59,695 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:08:59,696 DEBUG --- stdout --- 2025-03-28 02:08:59,697 DEBUG WAITING 2025-03-28 02:08:59,697 DEBUG --- stderr --- 2025-03-28 02:08:59,697 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 02:08:59] Reporting-mixedquery-10000 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2025-03-28 02:08:59,697 INFO 2025-03-28 02:08:59,697 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 02:08:59,697 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 02:09:00,045 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 02:09:00,045 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:00,045 DEBUG --- stdout --- 2025-03-28 02:09:00,045 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 02:09:00,045 DEBUG --- stderr --- 2025-03-28 02:09:00,045 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 02:09:00,046 INFO 2025-03-28 02:09:00,046 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/gatling/mixedquery-10000/mixedquery-10000.tar.gz" ]; then echo "/results/gatling/mixedquery-10000/mixedquery-10000.tar.gz file found"; exit 0; else echo "/results/gatling/mixedquery-10000/mixedquery-10000.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/mixedquery-10000/mixedquery-10000.tar.gz file found" 2025-03-28 02:09:00,046 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 02:09:00,909 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 02:09:00,909 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:00,909 DEBUG --- stdout --- 2025-03-28 02:09:00,909 DEBUG /results/gatling/mixedquery-10000/mixedquery-10000.tar.gz file found 2025-03-28 02:09:00,910 DEBUG --- stderr --- 2025-03-28 02:09:00,910 DEBUG 2025-03-28 02:09:00,910 INFO 2025-03-28 02:09:00,910 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/gatling/mixedquery-10000/mixedquery-10000.tar.gz 2025-03-28 02:09:01,708 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 02:09:01,709 DEBUG --- stdout --- 2025-03-28 02:09:01,709 DEBUG 320 /results/gatling/mixedquery-10000/mixedquery-10000.tar.gz 2025-03-28 02:09:01,709 DEBUG --- stderr --- 2025-03-28 02:09:01,709 DEBUG 2025-03-28 02:09:01,709 INFO - Download file (size 320k) source : /results/gatling/mixedquery-10000/mixedquery-10000.tar.gz @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/mixedquery-10000.tar.gz 2025-03-28 02:09:01,709 INFO 2025-03-28 02:09:01,709 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/gatling/mixedquery-10000/mixedquery-10000.tar.gz /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/mixedquery-10000.tar.gz 2025-03-28 02:09:01,709 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 02:09:02,704 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:02,704 DEBUG --- stdout --- 2025-03-28 02:09:02,704 DEBUG tar: Removing leading `/' from member names 2025-03-28 02:09:02,704 DEBUG --- stderr --- 2025-03-28 02:09:02,704 DEBUG ________________________________________________________________________________ [2025-03-28 02:09:02] Reporting-mixedquery-10000 step6 : Unzip the result ________________________________________________________________________________ 2025-03-28 02:09:02,704 INFO 2025-03-28 02:09:02,704 INFO [run_command]: tar zxf /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/shared/mixedquery-10000.tar.gz -C /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/06_mixedquery-10000/gatling 2025-03-28 02:09:02,720 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 02:09:02,720 DEBUG --- stdout --- 2025-03-28 02:09:02,720 DEBUG 2025-03-28 02:09:02,720 DEBUG --- stderr --- 2025-03-28 02:09:02,720 DEBUG 2025-03-28 02:09:02,720 INFO ________________________________________________________________________________ [2025-03-28 02:09:02] Reporting-mixedquery-10000 step7 : Print rc order ________________________________________________________________________________ 2025-03-28 02:09:02,720 INFO 2025-03-28 02:09:02,720 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 02:09:02,721 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 02:09:02,997 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 02:09:02,997 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:02,997 DEBUG --- stdout --- 2025-03-28 02:09:02,997 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 02:09:02,997 DEBUG --- stderr --- 2025-03-28 02:09:02,997 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 02:09:02,998 INFO 2025-03-28 02:09:02,998 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-mixedquery-10000/rc.txt" ]; then echo "/results/orders/Reporting-mixedquery-10000/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-10000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-10000/rc.txt file found" 2025-03-28 02:09:02,998 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 02:09:03,796 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 02:09:03,796 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:03,796 DEBUG --- stdout --- 2025-03-28 02:09:03,796 DEBUG /results/orders/Reporting-mixedquery-10000/rc.txt file found 2025-03-28 02:09:03,796 DEBUG --- stderr --- 2025-03-28 02:09:03,796 DEBUG 2025-03-28 02:09:03,796 INFO 2025-03-28 02:09:03,796 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-mixedquery-10000/rc.txt 2025-03-28 02:09:04,596 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 02:09:04,596 DEBUG --- stdout --- 2025-03-28 02:09:04,596 DEBUG 4 /results/orders/Reporting-mixedquery-10000/rc.txt 2025-03-28 02:09:04,596 DEBUG --- stderr --- 2025-03-28 02:09:04,596 DEBUG 2025-03-28 02:09:04,596 INFO - Download file (size 4k) source : /results/orders/Reporting-mixedquery-10000/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-mixedquery-10000/rc.txt 2025-03-28 02:09:04,597 INFO 2025-03-28 02:09:04,597 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-mixedquery-10000/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-mixedquery-10000/rc.txt 2025-03-28 02:09:04,597 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 02:09:05,323 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:05,323 DEBUG --- stdout --- 2025-03-28 02:09:05,323 DEBUG tar: Removing leading `/' from member names 2025-03-28 02:09:05,323 DEBUG --- stderr --- 2025-03-28 02:09:05,323 DEBUG 2025-03-28 02:09:05,323 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-mixedquery-10000/rc.txt is small (1 lines), printing all lines: 2025-03-28 02:09:05,323 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 02:09:05] Reporting-mixedquery-10000 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 02:09:05] Reporting-mixedquery-10000 step9 : Print stdout order ________________________________________________________________________________ 2025-03-28 02:09:05,565 INFO 2025-03-28 02:09:05,565 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 02:09:05,565 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 02:09:05,845 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 02:09:05,845 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:05,845 DEBUG --- stdout --- 2025-03-28 02:09:05,846 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 02:09:05,846 DEBUG --- stderr --- 2025-03-28 02:09:05,846 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 02:09:05,847 INFO 2025-03-28 02:09:05,847 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-mixedquery-10000/stdout.txt" ]; then echo "/results/orders/Reporting-mixedquery-10000/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-10000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-10000/stdout.txt file found" 2025-03-28 02:09:05,847 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 02:09:06,648 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 02:09:06,649 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:06,649 DEBUG --- stdout --- 2025-03-28 02:09:06,649 DEBUG /results/orders/Reporting-mixedquery-10000/stdout.txt file found 2025-03-28 02:09:06,649 DEBUG --- stderr --- 2025-03-28 02:09:06,649 DEBUG 2025-03-28 02:09:06,649 INFO 2025-03-28 02:09:06,649 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-mixedquery-10000/stdout.txt 2025-03-28 02:09:07,452 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 02:09:07,452 DEBUG --- stdout --- 2025-03-28 02:09:07,452 DEBUG 8 /results/orders/Reporting-mixedquery-10000/stdout.txt 2025-03-28 02:09:07,452 DEBUG --- stderr --- 2025-03-28 02:09:07,452 DEBUG 2025-03-28 02:09:07,453 INFO - Download file (size 8k) source : /results/orders/Reporting-mixedquery-10000/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-mixedquery-10000/stdout.txt 2025-03-28 02:09:07,453 INFO 2025-03-28 02:09:07,453 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-mixedquery-10000/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-mixedquery-10000/stdout.txt 2025-03-28 02:09:07,453 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 02:09:08,180 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:08,180 DEBUG --- stdout --- 2025-03-28 02:09:08,180 DEBUG tar: Removing leading `/' from member names 2025-03-28 02:09:08,180 DEBUG --- stderr --- 2025-03-28 02:09:08,180 DEBUG 2025-03-28 02:09:08,180 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-mixedquery-10000/stdout.txt is small (92 lines), printing all lines: 2025-03-28 02:09:08,180 INFO ----- output ----- 2025-03-28 01:55:04,358 INFO pom.xml copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpusmh4fg4 2025-03-28 01:55:04,360 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.reportsOnly="/results/gatling/mixedquery-10000/loadtest-20250328005346334" -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/tmpusmh4fg4/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/tmpusmh4fg4/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 --- 01:55:06,225 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version 1.5.8 01:55:06,226 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - No custom configurators were discovered as a service. 01:55:06,226 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.joran.SerializedModelConfigurator 01:55:06,227 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.joran.SerializedModelConfigurator 01:55:06,233 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.scmo] 01:55:06,233 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.scmo] 01:55:06,241 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.joran.SerializedModelConfigurator.configure() call lasted 7 milliseconds. ExecutionStatus=INVOKE_NEXT_IF_ANY 01:55:06,241 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Trying to configure with ch.qos.logback.classic.util.DefaultJoranConfigurator 01:55:06,241 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - Constructed configurator of type class ch.qos.logback.classic.util.DefaultJoranConfigurator 01:55:06,242 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 01:55:06,244 |-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] 01:55:06,249 |-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 01:55:06,294 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - element is deprecated. Near [level] on line 12 01:55:06,294 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within or elements instead. 01:55:06,334 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE] 01:55:06,334 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 01:55:06,340 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 01:55:06,362 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - ROOT level set to WARN 01:55:06,362 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT] 01:55:06,362 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@25a65b77 - End of configuration. 01:55:06,363 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2ed0fbae - Registering current configuration as safe fallback point 01:55:06,363 |-INFO in ch.qos.logback.classic.util.ContextInitializer@12d3a4e9 - ch.qos.logback.classic.util.DefaultJoranConfigurator.configure() call lasted 122 milliseconds. ExecutionStatus=DO_NOT_INVOKE_NEXT_IF_ANY Parsing log file(s)... Parsing log file(s) done in 826s. Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 17846768 (OK=17846761 KO=7 ) > min response time 39 (OK=39 KO=305 ) > max response time 13015 (OK=13015 KO=10043 ) > mean response time 47 (OK=47 KO=8628 ) > std deviation 13 (OK=12 KO=3398 ) > response time 50th percentile 47 (OK=47 KO=10004 ) > response time 75th percentile 48 (OK=48 KO=10021 ) > response time 95th percentile 51 (OK=51 KO=10039 ) > response time 99th percentile 58 (OK=58 KO=10042 ) > mean requests/sec 9914.87 (OK=9914.87 KO=0 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 17845964 ( 100%) > 800 ms <= t < 1200 ms 767 ( 0%) > t >= 1200 ms 30 ( 0%) > failed 7 ( 0%) ---- Errors -------------------------------------------------------------------- > j.i.IOException: Premature close 4 (57.14%) > i.n.c.ConnectTimeoutException: connection timed out after 1000 2 (28.57%) 0 ms: openam-perf-200m.forgeblocks.com/34.107.203.37:443 > status.find.in(200), but actually found 503 1 (14.29%) ================================================================================ Reports generated, please open the following file: file:///results/gatling/mixedquery-10000/loadtest-20250328005346334/index.html [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 13:49 min [INFO] Finished at: 2025-03-28T02:08:54Z [INFO] ------------------------------------------------------------------------ 2025-03-28 02:08:54,381 INFO Gatling simulation run ended successfully 2025-03-28 02:08:54,381 INFO Creating archive mixedquery-10000.tar.gz with report files 2025-03-28 02:08:54,480 INFO Archive /results/gatling/mixedquery-10000/mixedquery-10000.tar.gz successfully created ________________________________________________________________________________ [2025-03-28 02:09:08] Reporting-mixedquery-10000 step10 : Check stdout log and parse its content ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2025-03-28 02:09:08] Reporting-mixedquery-10000 step11 : Print stderr order ________________________________________________________________________________ 2025-03-28 02:09:08,181 INFO 2025-03-28 02:09:08,181 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 02:09:08,181 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 02:09:08,461 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 02:09:08,461 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:08,461 DEBUG --- stdout --- 2025-03-28 02:09:08,461 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 02:09:08,461 DEBUG --- stderr --- 2025-03-28 02:09:08,461 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 02:09:08,462 INFO 2025-03-28 02:09:08,463 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-mixedquery-10000/stderr.txt" ]; then echo "/results/orders/Reporting-mixedquery-10000/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-10000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-10000/stderr.txt file found" 2025-03-28 02:09:08,463 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 02:09:09,269 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 02:09:09,270 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:09,270 DEBUG --- stdout --- 2025-03-28 02:09:09,270 DEBUG /results/orders/Reporting-mixedquery-10000/stderr.txt file found 2025-03-28 02:09:09,270 DEBUG --- stderr --- 2025-03-28 02:09:09,270 DEBUG 2025-03-28 02:09:09,270 INFO 2025-03-28 02:09:09,270 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-mixedquery-10000/stderr.txt 2025-03-28 02:09:10,072 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 02:09:10,072 DEBUG --- stdout --- 2025-03-28 02:09:10,072 DEBUG 0 /results/orders/Reporting-mixedquery-10000/stderr.txt 2025-03-28 02:09:10,072 DEBUG --- stderr --- 2025-03-28 02:09:10,072 DEBUG 2025-03-28 02:09:10,072 INFO - Download file (size 0k) source : /results/orders/Reporting-mixedquery-10000/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-mixedquery-10000/stderr.txt 2025-03-28 02:09:10,073 INFO 2025-03-28 02:09:10,073 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/Reporting-mixedquery-10000/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-mixedquery-10000/stderr.txt 2025-03-28 02:09:10,073 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 02:09:10,896 INFO [loop_until]: OK (rc = 0) 2025-03-28 02:09:10,896 DEBUG --- stdout --- 2025-03-28 02:09:10,896 DEBUG tar: Removing leading `/' from member names 2025-03-28 02:09:10,896 DEBUG --- stderr --- 2025-03-28 02:09:10,896 DEBUG 2025-03-28 02:09:10,897 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/08_Verification/13_Reporting-mixedquery-10000/stderr.txt is small (0 lines), printing all lines: 2025-03-28 02:09:10,897 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 02:09:10] Reporting-mixedquery-10000 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2025-03-28 02:09:10] Reporting-mixedquery-10000 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 queryFull [warn] responseTimePercentiles was not found in /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/06_mixedquery-10000/gatling/req_queryfull--1807283337.html - cannot check for spikes checking simulation request ReadManagedUser [warn] responseTimePercentiles was not found in /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/06_mixedquery-10000/gatling/req_readmanageduser--1429285772.html - cannot check for spikes no spikes found ________________________________________________________________________________ [2025-03-28 02:09:10] Reporting-mixedquery-10000 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 17846768, "ok": 17846761, "ko": 7 }, "minResponseTime": { "total": 39, "ok": 39, "ko": 305 }, "maxResponseTime": { "total": 13015, "ok": 13015, "ko": 10043 }, "meanResponseTime": { "total": 47, "ok": 47, "ko": 8628 }, "standardDeviation": { "total": 13, "ok": 12, "ko": 3398 }, "percentiles1": { "total": 47, "ok": 47, "ko": 10004 }, "percentiles2": { "total": 48, "ok": 48, "ko": 10021 }, "percentiles3": { "total": 51, "ok": 51, "ko": 10039 }, "percentiles4": { "total": 58, "ok": 58, "ko": 10042 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 17845964, "percentage": 99.99549498262095 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 767, "percentage": 0.004297696927533322 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 30, "percentage": 0.00016809766339765273 }, "group4": { "name": "failed", "htmlName": "failed", "count": 7, "percentage": 3.922278812611897e-05 }, "meanNumberOfRequestsPerSecond": { "total": 9914.871111111112, "ok": 9914.867222222223, "ko": 0.0038888888888888888 } }, "details": [ { "name": "restLoginInitiate", "numberOfRequests": { "total": 1, "ok": 1, "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": 1, "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": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 118, "ok": 118, "ko": 0 }, "maxResponseTime": { "total": 118, "ok": 118, "ko": 0 }, "meanResponseTime": { "total": 118, "ok": 118, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 118, "ok": 118, "ko": 0 }, "percentiles2": { "total": 118, "ok": 118, "ko": 0 }, "percentiles3": { "total": 118, "ok": 118, "ko": 0 }, "percentiles4": { "total": 118, "ok": 118, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "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": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 89, "ok": 89, "ko": 0 }, "maxResponseTime": { "total": 89, "ok": 89, "ko": 0 }, "meanResponseTime": { "total": 89, "ok": 89, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 89, "ok": 89, "ko": 0 }, "percentiles2": { "total": 89, "ok": 89, "ko": 0 }, "percentiles3": { "total": 89, "ok": 89, "ko": 0 }, "percentiles4": { "total": 89, "ok": 89, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "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": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 68, "ok": 68, "ko": 0 }, "maxResponseTime": { "total": 68, "ok": 68, "ko": 0 }, "meanResponseTime": { "total": 68, "ok": 68, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 68, "ok": 68, "ko": 0 }, "percentiles2": { "total": 68, "ok": 68, "ko": 0 }, "percentiles3": { "total": 68, "ok": 68, "ko": 0 }, "percentiles4": { "total": 68, "ok": 68, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "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": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 62, "ok": 62, "ko": 0 }, "maxResponseTime": { "total": 62, "ok": 62, "ko": 0 }, "meanResponseTime": { "total": 62, "ok": 62, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 62, "ok": 62, "ko": 0 }, "percentiles2": { "total": 62, "ok": 62, "ko": 0 }, "percentiles3": { "total": 62, "ok": 62, "ko": 0 }, "percentiles4": { "total": 62, "ok": 62, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "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": "queryFull", "numberOfRequests": { "total": 8918982, "ok": 8918978, "ko": 4 }, "minResponseTime": { "total": 39, "ok": 39, "ko": 305 }, "maxResponseTime": { "total": 13010, "ok": 13010, "ko": 10014 }, "meanResponseTime": { "total": 47, "ok": 47, "ko": 7580 }, "standardDeviation": { "total": 13, "ok": 12, "ko": 4200 }, "percentiles1": { "total": 46, "ok": 46, "ko": 10001 }, "percentiles2": { "total": 48, "ok": 48, "ko": 10004 }, "percentiles3": { "total": 51, "ok": 51, "ko": 10012 }, "percentiles4": { "total": 58, "ok": 58, "ko": 10014 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 8918571, "percentage": 99.995391850774 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 393, "percentage": 0.004406332471575792 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 14, "percentage": 0.00015696858677369233 }, "group4": { "name": "failed", "htmlName": "failed", "count": 4, "percentage": 4.484816764962638e-05 }, "meanNumberOfRequestsPerSecond": { "total": 4954.99, "ok": 4954.987777777777, "ko": 0.0022222222222222222 }, "spikes": [] }, { "name": "ReadManagedUser", "numberOfRequests": { "total": 8927781, "ok": 8927778, "ko": 3 }, "minResponseTime": { "total": 39, "ok": 39, "ko": 10004 }, "maxResponseTime": { "total": 13015, "ok": 13015, "ko": 10043 }, "meanResponseTime": { "total": 47, "ok": 47, "ko": 10025 }, "standardDeviation": { "total": 14, "ok": 12, "ko": 16 }, "percentiles1": { "total": 47, "ok": 47, "ko": 10028 }, "percentiles2": { "total": 48, "ok": 48, "ko": 10036 }, "percentiles3": { "total": 51, "ok": 51, "ko": 10042 }, "percentiles4": { "total": 58, "ok": 58, "ko": 10043 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 8927388, "percentage": 99.99559801030065 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 374, "percentage": 0.004189170858917799 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 16, "percentage": 0.0001792158656221518 }, "group4": { "name": "failed", "htmlName": "failed", "count": 3, "percentage": 3.360297480415346e-05 }, "meanNumberOfRequestsPerSecond": { "total": 4959.878333333333, "ok": 4959.876666666667, "ko": 0.0016666666666666668 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 9915, "avg_response_time": 47, "max_response_time": 13015, "min_response_time": 39, "num_requests": 17846768, "num_requests_pass": 17846761, "num_requests_percent_pass": 100.0 }, "options": "; testMode:open; testCase:idm.MixedQuery; duration:1800; throughput:10000", "stats": { "details": [ { "group1": { "count": 1, "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": "restLoginInitiate", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "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": 1, "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": 118, "total": 118 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 118, "total": 118 }, "minResponseTime": { "ko": 0, "ok": 118, "total": 118 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 118, "total": 118 }, "percentiles2": { "ko": 0, "ok": 118, "total": 118 }, "percentiles3": { "ko": 0, "ok": 118, "total": 118 }, "percentiles4": { "ko": 0, "ok": 118, "total": 118 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 1, "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": 89, "total": 89 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 89, "total": 89 }, "minResponseTime": { "ko": 0, "ok": 89, "total": 89 }, "name": "Skip 2FA", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 89, "total": 89 }, "percentiles2": { "ko": 0, "ok": 89, "total": 89 }, "percentiles3": { "ko": 0, "ok": 89, "total": 89 }, "percentiles4": { "ko": 0, "ok": 89, "total": 89 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100.0 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0.0 }, "maxResponseTime": { "ko": 0, "ok": 68, "total": 68 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 68, "total": 68 }, "minResponseTime": { "ko": 0, "ok": 68, "total": 68 }, "name": "User Authorize PKCE stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 68, "total": 68 }, "percentiles2": { "ko": 0, "ok": 68, "total": 68 }, "percentiles3": { "ko": 0, "ok": 68, "total": 68 }, "percentiles4": { "ko": 0, "ok": 68, "total": 68 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 1, "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": 62, "total": 62 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005555555555555556, "total": 0.0005555555555555556 }, "meanResponseTime": { "ko": 0, "ok": 62, "total": 62 }, "minResponseTime": { "ko": 0, "ok": 62, "total": 62 }, "name": "User AccessToken PKCE stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 62, "total": 62 }, "percentiles2": { "ko": 0, "ok": 62, "total": 62 }, "percentiles3": { "ko": 0, "ok": 62, "total": 62 }, "percentiles4": { "ko": 0, "ok": 62, "total": 62 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 8918571, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.995391850774 }, "group2": { "count": 393, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.004406332471575792 }, "group3": { "count": 14, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.00015696858677369233 }, "group4": { "count": 4, "htmlName": "failed", "name": "failed", "percentage": 4.484816764962638e-05 }, "maxResponseTime": { "ko": 10014, "ok": 13010, "total": 13010 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0022222222222222222, "ok": 4954.987777777777, "total": 4954.99 }, "meanResponseTime": { "ko": 7580, "ok": 47, "total": 47 }, "minResponseTime": { "ko": 305, "ok": 39, "total": 39 }, "name": "queryFull", "numberOfRequests": { "ko": 4, "ok": 8918978, "total": 8918982 }, "percentiles1": { "ko": 10001, "ok": 46, "total": 46 }, "percentiles2": { "ko": 10004, "ok": 48, "total": 48 }, "percentiles3": { "ko": 10012, "ok": 51, "total": 51 }, "percentiles4": { "ko": 10014, "ok": 58, "total": 58 }, "spikes": [], "standardDeviation": { "ko": 4200, "ok": 12, "total": 13 } }, { "group1": { "count": 8927388, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.99559801030065 }, "group2": { "count": 374, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.004189170858917799 }, "group3": { "count": 16, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.0001792158656221518 }, "group4": { "count": 3, "htmlName": "failed", "name": "failed", "percentage": 3.360297480415346e-05 }, "maxResponseTime": { "ko": 10043, "ok": 13015, "total": 13015 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0016666666666666668, "ok": 4959.876666666667, "total": 4959.878333333333 }, "meanResponseTime": { "ko": 10025, "ok": 47, "total": 47 }, "minResponseTime": { "ko": 10004, "ok": 39, "total": 39 }, "name": "ReadManagedUser", "numberOfRequests": { "ko": 3, "ok": 8927778, "total": 8927781 }, "percentiles1": { "ko": 10028, "ok": 47, "total": 47 }, "percentiles2": { "ko": 10036, "ok": 48, "total": 48 }, "percentiles3": { "ko": 10042, "ok": 51, "total": 51 }, "percentiles4": { "ko": 10043, "ok": 58, "total": 58 }, "spikes": [], "standardDeviation": { "ko": 16, "ok": 12, "total": 14 } } ], "global": { "group1": { "count": 17845964, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99.99549498262095 }, "group2": { "count": 767, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0.004297696927533322 }, "group3": { "count": 30, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0.00016809766339765273 }, "group4": { "count": 7, "htmlName": "failed", "name": "failed", "percentage": 3.922278812611897e-05 }, "maxResponseTime": { "ko": 10043, "ok": 13015, "total": 13015 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0038888888888888888, "ok": 9914.867222222223, "total": 9914.871111111112 }, "meanResponseTime": { "ko": 8628, "ok": 47, "total": 47 }, "minResponseTime": { "ko": 305, "ok": 39, "total": 39 }, "name": "All Requests", "numberOfRequests": { "ko": 7, "ok": 17846761, "total": 17846768 }, "percentiles1": { "ko": 10004, "ok": 47, "total": 47 }, "percentiles2": { "ko": 10021, "ok": 48, "total": 48 }, "percentiles3": { "ko": 10039, "ok": 51, "total": 51 }, "percentiles4": { "ko": 10042, "ok": 58, "total": 58 }, "standardDeviation": { "ko": 3398, "ok": 12, "total": 13 } } }, "target_hostname": "openam-perf-200m.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2025-03-28 02:09:10] Reporting-mixedquery-10000 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2025-03-28 02:09:10] Reporting-mixedquery-10000 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/13_Reporting-mixedquery-10000/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2025-03-28 02:09:10] Reporting-mixedquery-10000 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped