--Task-- name: Reporting-mixedquery-70 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 bellow loop: False interval: None dependencies: [] wait_for: ['Reporting-mixedquery-10', 'Reporting-mixedquery-20', 'Reporting-mixedquery-30', 'Reporting-mixedquery-40', 'Reporting-mixedquery-50', 'Reporting-mixedquery-60'] preceding_task: None options: {'task_name': 'mixedquery-70'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 09:57:57] Reporting-mixedquery-70 pre : N/A ________________________________________________________________________________ source (overseer-0) is a component [2024-04-24 09:57:57] - INFO: Timeout for this Task has changed to 2h (7200 seconds) [2024-04-24 09:57:57] - 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-70/stdout.txt Pod stderr file : /results/orders/Reporting-mixedquery-70/stderr.txt Pod log : /results/orders/Reporting-mixedquery-70 ________________________________________________________________________________ [2024-04-24 09:57:57] Reporting-mixedquery-70 step1 : Write order for overseer to generate the report ________________________________________________________________________________ 2024-04-24 09:57:57,958 INFO 2024-04-24 09:57:57,958 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 2024-04-24 09:57:57,958 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:58,308 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:58,308 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:58,308 DEBUG --- stdout --- 2024-04-24 09:57:58,308 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:58,309 DEBUG --- stderr --- 2024-04-24 09:57:58,309 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/15_Reporting-mixedquery-70/order.json { "name": "Reporting-mixedquery-70", "command": "/lodestar/clients/gatling-report-only-for-pyrock.sh mixedquery-70 --extra-run-jvm-options \"-Xmx12G\"", "out-dir": "/results/orders/Reporting-mixedquery-70" } ________________________________________________________________________________ [2024-04-24 09:57:58] Reporting-mixedquery-70 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2024-04-24 09:57:58,310 INFO 2024-04-24 09:57:58,310 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:57:58,310 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2024-04-24 09:57:58,628 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:57:58,629 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:58,629 DEBUG --- stdout --- 2024-04-24 09:57:58,629 DEBUG WAITING 2024-04-24 09:57:58,629 DEBUG --- stderr --- 2024-04-24 09:57:58,629 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 24 0 --:--:-- --:--:-- --:--:-- 24 ________________________________________________________________________________ [2024-04-24 09:57:58] Reporting-mixedquery-70 step3 : Send order to overseer ________________________________________________________________________________ 2024-04-24 09:57:58,630 INFO 2024-04-24 09:57:58,630 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 2024-04-24 09:57:58,630 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:58,969 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:58,969 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:58,969 DEBUG --- stdout --- 2024-04-24 09:57:58,970 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:58,970 DEBUG --- stderr --- 2024-04-24 09:57:58,970 DEBUG 2024-04-24 09:57:58,970 INFO 2024-04-24 09:57:58,970 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/15_Reporting-mixedquery-70/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 09:57:58,970 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:58:00,372 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:00,372 DEBUG --- stdout --- 2024-04-24 09:58:00,372 DEBUG 2024-04-24 09:58:00,372 DEBUG --- stderr --- 2024-04-24 09:58:00,373 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2024-04-24 09:58:00] Reporting-mixedquery-70 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2024-04-24 09:58:05,379 INFO 2024-04-24 09:58:05,379 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:58:05,380 INFO [loop_until]: (max_time=7200, interval=5, expected_rc=[0] 2024-04-24 09:58:05,624 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2024-04-24 09:58:10,873 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2024-04-24 09:58:10,873 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:10,873 DEBUG --- stdout --- 2024-04-24 09:58:10,873 DEBUG WAITING 2024-04-24 09:58:10,873 DEBUG --- stderr --- 2024-04-24 09:58:10,873 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 ________________________________________________________________________________ [2024-04-24 09:58:10] Reporting-mixedquery-70 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2024-04-24 09:58:10,874 INFO 2024-04-24 09:58:10,874 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 2024-04-24 09:58:10,875 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:11,205 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:11,206 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:11,206 DEBUG --- stdout --- 2024-04-24 09:58:11,206 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:11,206 DEBUG --- stderr --- 2024-04-24 09:58:11,206 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:58:11,206 INFO 2024-04-24 09:58:11,206 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 2024-04-24 09:58:11,206 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:11,532 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:11,533 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:11,533 DEBUG --- stdout --- 2024-04-24 09:58:11,533 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:11,533 DEBUG --- stderr --- 2024-04-24 09:58:11,533 DEBUG 2024-04-24 09:58:11,534 INFO 2024-04-24 09:58:11,534 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- bash -c 'if [ -f "/results/gatling/mixedquery-70.tar.gz" ]; then echo "/results/gatling/mixedquery-70.tar.gz file found"; exit 0; else echo "/results/gatling/mixedquery-70.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/mixedquery-70.tar.gz file found" 2024-04-24 09:58:11,534 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:58:12,379 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:58:12,379 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:12,379 DEBUG --- stdout --- 2024-04-24 09:58:12,379 DEBUG /results/gatling/mixedquery-70.tar.gz file found 2024-04-24 09:58:12,379 DEBUG --- stderr --- 2024-04-24 09:58:12,379 DEBUG 2024-04-24 09:58:12,380 INFO 2024-04-24 09:58:12,380 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 2024-04-24 09:58:12,380 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:12,720 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:12,721 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:12,721 DEBUG --- stdout --- 2024-04-24 09:58:12,721 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:12,721 DEBUG --- stderr --- 2024-04-24 09:58:12,721 DEBUG 2024-04-24 09:58:12,721 INFO 2024-04-24 09:58:12,721 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- du -k /results/gatling/mixedquery-70.tar.gz 2024-04-24 09:58:13,574 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:58:13,574 DEBUG --- stdout --- 2024-04-24 09:58:13,574 DEBUG 360 /results/gatling/mixedquery-70.tar.gz 2024-04-24 09:58:13,574 DEBUG --- stderr --- 2024-04-24 09:58:13,574 DEBUG 2024-04-24 09:58:13,574 INFO - Download file (size 360k) 2024-04-24 09:58:13,575 INFO 2024-04-24 09:58:13,575 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 2024-04-24 09:58:13,575 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:13,904 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:13,904 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:13,904 DEBUG --- stdout --- 2024-04-24 09:58:13,904 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:13,904 DEBUG --- stderr --- 2024-04-24 09:58:13,904 DEBUG source : /results/gatling/mixedquery-70.tar.gz @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-70.tar.gz 2024-04-24 09:58:13,905 INFO 2024-04-24 09:58:13,905 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 2024-04-24 09:58:13,905 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:14,250 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:14,250 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:14,250 DEBUG --- stdout --- 2024-04-24 09:58:14,250 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:14,250 DEBUG --- stderr --- 2024-04-24 09:58:14,250 DEBUG 2024-04-24 09:58:14,251 INFO 2024-04-24 09:58:14,251 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/gatling/mixedquery-70.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-70.tar.gz 2024-04-24 09:58:14,251 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:58:15,319 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:15,320 DEBUG --- stdout --- 2024-04-24 09:58:15,320 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:58:15,320 DEBUG --- stderr --- 2024-04-24 09:58:15,320 DEBUG ________________________________________________________________________________ [2024-04-24 09:58:15] Reporting-mixedquery-70 step6 : Unzip the result ________________________________________________________________________________ 2024-04-24 09:58:15,322 INFO 2024-04-24 09:58:15,322 INFO [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-70.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/gatling 2024-04-24 09:58:15,382 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:58:15,382 DEBUG --- stdout --- 2024-04-24 09:58:15,382 DEBUG 2024-04-24 09:58:15,382 DEBUG --- stderr --- 2024-04-24 09:58:15,382 DEBUG 2024-04-24 09:58:15,382 INFO ________________________________________________________________________________ [2024-04-24 09:58:15] Reporting-mixedquery-70 step7 : Print rc order ________________________________________________________________________________ 2024-04-24 09:58:15,384 INFO 2024-04-24 09:58:15,384 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 2024-04-24 09:58:15,384 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:15,717 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:15,717 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:15,717 DEBUG --- stdout --- 2024-04-24 09:58:15,718 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:15,718 DEBUG --- stderr --- 2024-04-24 09:58:15,718 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:58:15,718 INFO 2024-04-24 09:58:15,718 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 2024-04-24 09:58:15,718 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:16,090 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:16,090 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:16,091 DEBUG --- stdout --- 2024-04-24 09:58:16,091 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:16,091 DEBUG --- stderr --- 2024-04-24 09:58:16,091 DEBUG 2024-04-24 09:58:16,092 INFO 2024-04-24 09:58:16,092 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- bash -c 'if [ -f "/results/orders/Reporting-mixedquery-70/rc.txt" ]; then echo "/results/orders/Reporting-mixedquery-70/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-70/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-70/rc.txt file found" 2024-04-24 09:58:16,092 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:58:16,970 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:58:16,970 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:16,970 DEBUG --- stdout --- 2024-04-24 09:58:16,970 DEBUG /results/orders/Reporting-mixedquery-70/rc.txt file found 2024-04-24 09:58:16,970 DEBUG --- stderr --- 2024-04-24 09:58:16,970 DEBUG 2024-04-24 09:58:16,971 INFO 2024-04-24 09:58:16,971 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 2024-04-24 09:58:16,971 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:17,319 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:17,319 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:17,319 DEBUG --- stdout --- 2024-04-24 09:58:17,319 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:17,319 DEBUG --- stderr --- 2024-04-24 09:58:17,319 DEBUG 2024-04-24 09:58:17,319 INFO 2024-04-24 09:58:17,319 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- du -k /results/orders/Reporting-mixedquery-70/rc.txt 2024-04-24 09:58:18,203 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:58:18,203 DEBUG --- stdout --- 2024-04-24 09:58:18,203 DEBUG 4 /results/orders/Reporting-mixedquery-70/rc.txt 2024-04-24 09:58:18,204 DEBUG --- stderr --- 2024-04-24 09:58:18,204 DEBUG 2024-04-24 09:58:18,204 INFO - Download file (size 4k) 2024-04-24 09:58:18,204 INFO 2024-04-24 09:58:18,204 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 2024-04-24 09:58:18,204 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:18,581 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:18,581 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:18,581 DEBUG --- stdout --- 2024-04-24 09:58:18,581 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:18,581 DEBUG --- stderr --- 2024-04-24 09:58:18,581 DEBUG source : /results/orders/Reporting-mixedquery-70/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/15_Reporting-mixedquery-70/rc.txt 2024-04-24 09:58:18,581 INFO 2024-04-24 09:58:18,581 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 2024-04-24 09:58:18,582 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:18,916 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:18,916 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:18,916 DEBUG --- stdout --- 2024-04-24 09:58:18,916 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:18,916 DEBUG --- stderr --- 2024-04-24 09:58:18,916 DEBUG 2024-04-24 09:58:18,917 INFO 2024-04-24 09:58:18,917 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/Reporting-mixedquery-70/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/15_Reporting-mixedquery-70/rc.txt 2024-04-24 09:58:18,918 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:58:19,756 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:19,756 DEBUG --- stdout --- 2024-04-24 09:58:19,756 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:58:19,756 DEBUG --- stderr --- 2024-04-24 09:58:19,757 DEBUG 2024-04-24 09:58:19,757 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/15_Reporting-mixedquery-70/rc.txt is small (1 lines), printing all lines: 2024-04-24 09:58:19,757 DEBUG ----- output ----- 2024-04-24 09:58:19,757 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 09:58:19,757 INFO ________________________________________________________________________________ [2024-04-24 09:58:19] Reporting-mixedquery-70 step8 : Check rc log ________________________________________________________________________________ PASS: no error found in rc log ________________________________________________________________________________ [2024-04-24 09:58:19] Reporting-mixedquery-70 step9 : Print stdout order ________________________________________________________________________________ 2024-04-24 09:58:19,759 INFO 2024-04-24 09:58:19,759 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 2024-04-24 09:58:19,759 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:20,144 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:20,145 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:20,145 DEBUG --- stdout --- 2024-04-24 09:58:20,145 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:20,145 DEBUG --- stderr --- 2024-04-24 09:58:20,145 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:58:20,145 INFO 2024-04-24 09:58:20,145 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 2024-04-24 09:58:20,145 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:20,537 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:20,537 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:20,537 DEBUG --- stdout --- 2024-04-24 09:58:20,537 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:20,537 DEBUG --- stderr --- 2024-04-24 09:58:20,537 DEBUG 2024-04-24 09:58:20,538 INFO 2024-04-24 09:58:20,538 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- bash -c 'if [ -f "/results/orders/Reporting-mixedquery-70/stdout.txt" ]; then echo "/results/orders/Reporting-mixedquery-70/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-70/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-70/stdout.txt file found" 2024-04-24 09:58:20,538 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:58:21,399 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:58:21,399 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:21,399 DEBUG --- stdout --- 2024-04-24 09:58:21,400 DEBUG /results/orders/Reporting-mixedquery-70/stdout.txt file found 2024-04-24 09:58:21,400 DEBUG --- stderr --- 2024-04-24 09:58:21,400 DEBUG 2024-04-24 09:58:21,400 INFO 2024-04-24 09:58:21,400 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 2024-04-24 09:58:21,400 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:21,779 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:21,779 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:21,780 DEBUG --- stdout --- 2024-04-24 09:58:21,780 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:21,780 DEBUG --- stderr --- 2024-04-24 09:58:21,780 DEBUG 2024-04-24 09:58:21,780 INFO 2024-04-24 09:58:21,780 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- du -k /results/orders/Reporting-mixedquery-70/stdout.txt 2024-04-24 09:58:22,680 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:58:22,680 DEBUG --- stdout --- 2024-04-24 09:58:22,680 DEBUG 4 /results/orders/Reporting-mixedquery-70/stdout.txt 2024-04-24 09:58:22,680 DEBUG --- stderr --- 2024-04-24 09:58:22,680 DEBUG 2024-04-24 09:58:22,680 INFO - Download file (size 4k) 2024-04-24 09:58:22,680 INFO 2024-04-24 09:58:22,681 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 2024-04-24 09:58:22,681 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:23,011 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:23,011 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:23,011 DEBUG --- stdout --- 2024-04-24 09:58:23,011 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:23,011 DEBUG --- stderr --- 2024-04-24 09:58:23,011 DEBUG source : /results/orders/Reporting-mixedquery-70/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/15_Reporting-mixedquery-70/stdout.txt 2024-04-24 09:58:23,011 INFO 2024-04-24 09:58:23,012 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 2024-04-24 09:58:23,012 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:23,363 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:23,363 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:23,364 DEBUG --- stdout --- 2024-04-24 09:58:23,364 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:23,364 DEBUG --- stderr --- 2024-04-24 09:58:23,364 DEBUG 2024-04-24 09:58:23,364 INFO 2024-04-24 09:58:23,364 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/Reporting-mixedquery-70/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/15_Reporting-mixedquery-70/stdout.txt 2024-04-24 09:58:23,364 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:58:24,144 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:24,145 DEBUG --- stdout --- 2024-04-24 09:58:24,145 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:58:24,145 DEBUG --- stderr --- 2024-04-24 09:58:24,145 DEBUG 2024-04-24 09:58:24,145 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/15_Reporting-mixedquery-70/stdout.txt is small (81 lines), printing all lines: 2024-04-24 09:58:24,145 DEBUG ----- output ----- 2024-04-24 09:58:24,145 DEBUG [cmd] /lodestar/clients/gatling/bin/gatling.sh --reports-only /results/gatling/mixedquery-70/loadtest-20240424084914970 --extra-run-jvm-options -Xmx12G GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Generating Gatling reports locally Parsing log file(s)... Parsing log file(s) done Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 127204 (OK=127204 KO=0 ) > min response time 5 (OK=5 KO=- ) > max response time 3170 (OK=3170 KO=- ) > mean response time 153 (OK=153 KO=- ) > std deviation 278 (OK=278 KO=- ) > response time 50th percentile 8 (OK=8 KO=- ) > response time 75th percentile 246 (OK=246 KO=- ) > response time 95th percentile 415 (OK=415 KO=- ) > response time 99th percentile 1574 (OK=1575 KO=- ) > mean requests/sec 69.472 (OK=69.472 KO=- ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 123233 ( 97%) > 800 ms <= t < 1200 ms 1823 ( 1%) > t >= 1200 ms 2148 ( 2%) > failed 0 ( 0%) ================================================================================ Reports generated in 0s. Please open the following file: file:///results/gatling/mixedquery-70/loadtest-20240424084914970/index.html ---------------------- [24/Apr/2024 10:58:09] Create tar file /results/gatling/mixedquery-70.tar.gz ---------------------- SIMULATION_PATH=/results/gatling/mixedquery-70/loadtest-20240424084914970 RESULT_FOLDER=mixedquery-70 RESULT_PATH=/results/gatling/mixedquery-70 PWD=/results/gatling/mixedquery-70/loadtest-20240424084914970 [cmd] tar -czvf /results/gatling/mixedquery-70.tar.gz --exclude=simulation.log * group_retrievevariabl-5a49b.html group_storevariables-5102b.html index.html js/ js/global_stats.json js/menu.js js/all_sessions.js js/unpack.js js/highcharts-more.js js/jquery-3.5.1.min.js js/bootstrap.min.js js/stats.js js/highstock.js js/ellipsis.js js/assertions.xml js/stats.json js/assertions.json js/gatling.js js/theme.js req_admin-accesstok-f0286.html req_adminauthorize-f6e0c.html req_adminrestlogin-b480c.html req_querybysn-65c9b.html req_queryfull-e5d3d.html req_querypartial-f2acd.html req_querystartswith-1e9cd.html req_readmanageduser-f3f1e.html style/ style/logo-enterprise.svg style/arrow_down_black.png style/arrow_right_black.png style/sorted-down.png style/arrow_down.png style/stat-l-temps.png style/little_arrow_right.png style/favicon.ico style/sortable.png style/arrow_right.png style/logo.svg style/stat-fleche-bas.png style/sorted-up.png style/bootstrap.min.css style/style.css style/stat-l-roue.png 2024-04-24 09:58:24,145 INFO ________________________________________________________________________________ [2024-04-24 09:58:24] Reporting-mixedquery-70 step10 : Check stdout log ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2024-04-24 09:58:24] Reporting-mixedquery-70 step11 : Print stderr order ________________________________________________________________________________ 2024-04-24 09:58:24,147 INFO 2024-04-24 09:58:24,147 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 2024-04-24 09:58:24,147 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:24,473 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:24,473 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:24,473 DEBUG --- stdout --- 2024-04-24 09:58:24,473 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:24,473 DEBUG --- stderr --- 2024-04-24 09:58:24,473 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:58:24,473 INFO 2024-04-24 09:58:24,473 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 2024-04-24 09:58:24,473 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:24,800 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:24,800 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:24,801 DEBUG --- stdout --- 2024-04-24 09:58:24,801 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:24,801 DEBUG --- stderr --- 2024-04-24 09:58:24,801 DEBUG 2024-04-24 09:58:24,802 INFO 2024-04-24 09:58:24,802 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- bash -c 'if [ -f "/results/orders/Reporting-mixedquery-70/stderr.txt" ]; then echo "/results/orders/Reporting-mixedquery-70/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-70/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-70/stderr.txt file found" 2024-04-24 09:58:24,802 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:58:25,681 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:58:25,681 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:25,681 DEBUG --- stdout --- 2024-04-24 09:58:25,681 DEBUG /results/orders/Reporting-mixedquery-70/stderr.txt file found 2024-04-24 09:58:25,681 DEBUG --- stderr --- 2024-04-24 09:58:25,681 DEBUG 2024-04-24 09:58:25,682 INFO 2024-04-24 09:58:25,682 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 2024-04-24 09:58:25,682 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:26,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:26,029 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:26,029 DEBUG --- stdout --- 2024-04-24 09:58:26,029 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:26,029 DEBUG --- stderr --- 2024-04-24 09:58:26,029 DEBUG 2024-04-24 09:58:26,030 INFO 2024-04-24 09:58:26,030 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- du -k /results/orders/Reporting-mixedquery-70/stderr.txt 2024-04-24 09:58:26,937 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:58:26,938 DEBUG --- stdout --- 2024-04-24 09:58:26,938 DEBUG 0 /results/orders/Reporting-mixedquery-70/stderr.txt 2024-04-24 09:58:26,938 DEBUG --- stderr --- 2024-04-24 09:58:26,938 DEBUG 2024-04-24 09:58:26,938 INFO - Download file (size 0k) 2024-04-24 09:58:26,939 INFO 2024-04-24 09:58:26,939 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 2024-04-24 09:58:26,939 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:27,311 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:27,312 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:27,312 DEBUG --- stdout --- 2024-04-24 09:58:27,312 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:27,312 DEBUG --- stderr --- 2024-04-24 09:58:27,312 DEBUG source : /results/orders/Reporting-mixedquery-70/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/15_Reporting-mixedquery-70/stderr.txt 2024-04-24 09:58:27,313 INFO 2024-04-24 09:58:27,313 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 2024-04-24 09:58:27,313 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:58:27,680 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:58:27,680 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:27,680 DEBUG --- stdout --- 2024-04-24 09:58:27,680 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:58:27,680 DEBUG --- stderr --- 2024-04-24 09:58:27,680 DEBUG 2024-04-24 09:58:27,681 INFO 2024-04-24 09:58:27,681 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/Reporting-mixedquery-70/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/15_Reporting-mixedquery-70/stderr.txt 2024-04-24 09:58:27,681 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:58:28,491 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:58:28,491 DEBUG --- stdout --- 2024-04-24 09:58:28,491 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:58:28,491 DEBUG --- stderr --- 2024-04-24 09:58:28,491 DEBUG 2024-04-24 09:58:28,491 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/15_Reporting-mixedquery-70/stderr.txt is small (0 lines), printing all lines: 2024-04-24 09:58:28,491 DEBUG ----- output ----- 2024-04-24 09:58:28,491 DEBUG 2024-04-24 09:58:28,491 INFO ________________________________________________________________________________ [2024-04-24 09:58:28] Reporting-mixedquery-70 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-24 09:58:28] Reporting-mixedquery-70 step13 : Analyze graphs for spikes ________________________________________________________________________________ Analyze Gatling graphs for spikes checking parameters open overall stats JSON file checking simulation request AdminRestLogin checking simulation request AdminAuthorize checking simulation request Admin AccessToken stage checking simulation request queryFull checking simulation request queryStartsWith [warn] responseTimePercentiles was not found in /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/gatling/req_querystartswith-1e9cd.html - cannot check for spikes checking simulation request queryBySn checking simulation request queryPartial [warn] responseTimePercentiles was not found in /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/gatling/req_querypartial-f2acd.html - cannot check for spikes checking simulation request ReadManagedUser no spikes found ________________________________________________________________________________ [2024-04-24 09:58:28] Reporting-mixedquery-70 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 127204, "ok": 127204, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 3170, "ok": 3170, "ko": 0 }, "meanResponseTime": { "total": 153, "ok": 153, "ko": 0 }, "standardDeviation": { "total": 278, "ok": 278, "ko": 0 }, "percentiles1": { "total": 8, "ok": 8, "ko": 0 }, "percentiles2": { "total": 246, "ok": 246, "ko": 0 }, "percentiles3": { "total": 415, "ok": 415, "ko": 0 }, "percentiles4": { "total": 1574, "ok": 1575, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 123233, "percentage": 97 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 1823, "percentage": 1 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 2148, "percentage": 2 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 69.47241944292736, "ok": 69.47241944292736, "ko": 0 } }, "details": [ { "name": "AdminRestLogin", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 39, "ok": 39, "ko": 0 }, "maxResponseTime": { "total": 39, "ok": 39, "ko": 0 }, "meanResponseTime": { "total": 39, "ok": 39, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 39, "ok": 39, "ko": 0 }, "percentiles2": { "total": 39, "ok": 39, "ko": 0 }, "percentiles3": { "total": 39, "ok": 39, "ko": 0 }, "percentiles4": { "total": 39, "ok": 39, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005461496450027307, "ok": 0.0005461496450027307, "ko": 0 }, "spikes": [] }, { "name": "AdminAuthorize", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 26, "ok": 26, "ko": 0 }, "maxResponseTime": { "total": 26, "ok": 26, "ko": 0 }, "meanResponseTime": { "total": 26, "ok": 26, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 26, "ok": 26, "ko": 0 }, "percentiles2": { "total": 26, "ok": 26, "ko": 0 }, "percentiles3": { "total": 26, "ok": 26, "ko": 0 }, "percentiles4": { "total": 26, "ok": 26, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005461496450027307, "ok": 0.0005461496450027307, "ko": 0 }, "spikes": [] }, { "name": "Admin AccessToken stage", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 23, "ok": 23, "ko": 0 }, "maxResponseTime": { "total": 23, "ok": 23, "ko": 0 }, "meanResponseTime": { "total": 23, "ok": 23, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 23, "ok": 23, "ko": 0 }, "percentiles2": { "total": 23, "ok": 23, "ko": 0 }, "percentiles3": { "total": 23, "ok": 23, "ko": 0 }, "percentiles4": { "total": 23, "ok": 23, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005461496450027307, "ok": 0.0005461496450027307, "ko": 0 }, "spikes": [] }, { "name": "queryFull", "numberOfRequests": { "total": 25285, "ok": 25285, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 1742, "ok": 1742, "ko": 0 }, "meanResponseTime": { "total": 33, "ok": 33, "ko": 0 }, "standardDeviation": { "total": 144, "ok": 144, "ko": 0 }, "percentiles1": { "total": 7, "ok": 7, "ko": 0 }, "percentiles2": { "total": 8, "ok": 8, "ko": 0 }, "percentiles3": { "total": 17, "ok": 17, "ko": 0 }, "percentiles4": { "total": 940, "ok": 940, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 24927, "percentage": 99 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 296, "percentage": 1 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 62, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 13.809393773894048, "ok": 13.809393773894048, "ko": 0 }, "spikes": [] }, { "name": "queryStartsWith", "numberOfRequests": { "total": 25625, "ok": 25625, "ko": 0 }, "minResponseTime": { "total": 87, "ok": 87, "ko": 0 }, "maxResponseTime": { "total": 3170, "ok": 3170, "ko": 0 }, "meanResponseTime": { "total": 350, "ok": 350, "ko": 0 }, "standardDeviation": { "total": 383, "ok": 383, "ko": 0 }, "percentiles1": { "total": 250, "ok": 250, "ko": 0 }, "percentiles2": { "total": 274, "ok": 274, "ko": 0 }, "percentiles3": { "total": 1182, "ok": 1182, "ko": 0 }, "percentiles4": { "total": 2332, "ok": 2332, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 23841, "percentage": 93 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 521, "percentage": 2 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 1263, "percentage": 5 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 13.995084653194976, "ok": 13.995084653194976, "ko": 0 }, "spikes": [] }, { "name": "queryBySn", "numberOfRequests": { "total": 25330, "ok": 25330, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 1811, "ok": 1811, "ko": 0 }, "meanResponseTime": { "total": 33, "ok": 33, "ko": 0 }, "standardDeviation": { "total": 143, "ok": 143, "ko": 0 }, "percentiles1": { "total": 7, "ok": 7, "ko": 0 }, "percentiles2": { "total": 8, "ok": 8, "ko": 0 }, "percentiles3": { "total": 18, "ok": 18, "ko": 0 }, "percentiles4": { "total": 921, "ok": 921, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 24978, "percentage": 99 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 296, "percentage": 1 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 56, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 13.83397050791917, "ok": 13.83397050791917, "ko": 0 }, "spikes": [] }, { "name": "queryPartial", "numberOfRequests": { "total": 25372, "ok": 25372, "ko": 0 }, "minResponseTime": { "total": 164, "ok": 164, "ko": 0 }, "maxResponseTime": { "total": 2765, "ok": 2765, "ko": 0 }, "meanResponseTime": { "total": 318, "ok": 318, "ko": 0 }, "standardDeviation": { "total": 260, "ok": 260, "ko": 0 }, "percentiles1": { "total": 255, "ok": 255, "ko": 0 }, "percentiles2": { "total": 286, "ok": 286, "ko": 0 }, "percentiles3": { "total": 724, "ok": 724, "ko": 0 }, "percentiles4": { "total": 1746, "ok": 1746, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 24255, "percentage": 96 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 411, "percentage": 2 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 706, "percentage": 3 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 13.856908793009284, "ok": 13.856908793009284, "ko": 0 }, "spikes": [] }, { "name": "ReadManagedUser", "numberOfRequests": { "total": 25589, "ok": 25589, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 1824, "ok": 1824, "ko": 0 }, "meanResponseTime": { "total": 32, "ok": 32, "ko": 0 }, "standardDeviation": { "total": 144, "ok": 144, "ko": 0 }, "percentiles1": { "total": 6, "ok": 6, "ko": 0 }, "percentiles2": { "total": 7, "ok": 7, "ko": 0 }, "percentiles3": { "total": 14, "ok": 15, "ko": 0 }, "percentiles4": { "total": 921, "ok": 921, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 25229, "percentage": 99 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 299, "percentage": 1 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 61, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 13.975423265974877, "ok": 13.975423265974877, "ko": 0 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 69, "avg_response_time": 153, "max_response_time": 3170, "min_response_time": 5, "num_requests": 127204, "num_requests_pass": 127204, "num_requests_percent_pass": 100.0 }, "options": "; duration:1800; simulation:com.forgerock.gatling.Loadtest; testCase:idm.MixedQuery; testMode:open; throughput:70", "stats": { "details": [ { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 39, "total": 39 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 39, "total": 39 }, "minResponseTime": { "ko": 0, "ok": 39, "total": 39 }, "name": "AdminRestLogin", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 39, "total": 39 }, "percentiles2": { "ko": 0, "ok": 39, "total": 39 }, "percentiles3": { "ko": 0, "ok": 39, "total": 39 }, "percentiles4": { "ko": 0, "ok": 39, "total": 39 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 26, "total": 26 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 26, "total": 26 }, "minResponseTime": { "ko": 0, "ok": 26, "total": 26 }, "name": "AdminAuthorize", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 26, "total": 26 }, "percentiles2": { "ko": 0, "ok": 26, "total": 26 }, "percentiles3": { "ko": 0, "ok": 26, "total": 26 }, "percentiles4": { "ko": 0, "ok": 26, "total": 26 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 23, "total": 23 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 23, "total": 23 }, "minResponseTime": { "ko": 0, "ok": 23, "total": 23 }, "name": "Admin AccessToken stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 23, "total": 23 }, "percentiles2": { "ko": 0, "ok": 23, "total": 23 }, "percentiles3": { "ko": 0, "ok": 23, "total": 23 }, "percentiles4": { "ko": 0, "ok": 23, "total": 23 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 24927, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99 }, "group2": { "count": 296, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 1 }, "group3": { "count": 62, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 1742, "total": 1742 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 13.809393773894048, "total": 13.809393773894048 }, "meanResponseTime": { "ko": 0, "ok": 33, "total": 33 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryFull", "numberOfRequests": { "ko": 0, "ok": 25285, "total": 25285 }, "percentiles1": { "ko": 0, "ok": 7, "total": 7 }, "percentiles2": { "ko": 0, "ok": 8, "total": 8 }, "percentiles3": { "ko": 0, "ok": 17, "total": 17 }, "percentiles4": { "ko": 0, "ok": 940, "total": 940 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 144, "total": 144 } }, { "group1": { "count": 23841, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 93 }, "group2": { "count": 521, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 2 }, "group3": { "count": 1263, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 5 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 3170, "total": 3170 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 13.995084653194976, "total": 13.995084653194976 }, "meanResponseTime": { "ko": 0, "ok": 350, "total": 350 }, "minResponseTime": { "ko": 0, "ok": 87, "total": 87 }, "name": "queryStartsWith", "numberOfRequests": { "ko": 0, "ok": 25625, "total": 25625 }, "percentiles1": { "ko": 0, "ok": 250, "total": 250 }, "percentiles2": { "ko": 0, "ok": 274, "total": 274 }, "percentiles3": { "ko": 0, "ok": 1182, "total": 1182 }, "percentiles4": { "ko": 0, "ok": 2332, "total": 2332 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 383, "total": 383 } }, { "group1": { "count": 24978, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99 }, "group2": { "count": 296, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 1 }, "group3": { "count": 56, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 1811, "total": 1811 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 13.83397050791917, "total": 13.83397050791917 }, "meanResponseTime": { "ko": 0, "ok": 33, "total": 33 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryBySn", "numberOfRequests": { "ko": 0, "ok": 25330, "total": 25330 }, "percentiles1": { "ko": 0, "ok": 7, "total": 7 }, "percentiles2": { "ko": 0, "ok": 8, "total": 8 }, "percentiles3": { "ko": 0, "ok": 18, "total": 18 }, "percentiles4": { "ko": 0, "ok": 921, "total": 921 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 143, "total": 143 } }, { "group1": { "count": 24255, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 96 }, "group2": { "count": 411, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 2 }, "group3": { "count": 706, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 3 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 2765, "total": 2765 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 13.856908793009284, "total": 13.856908793009284 }, "meanResponseTime": { "ko": 0, "ok": 318, "total": 318 }, "minResponseTime": { "ko": 0, "ok": 164, "total": 164 }, "name": "queryPartial", "numberOfRequests": { "ko": 0, "ok": 25372, "total": 25372 }, "percentiles1": { "ko": 0, "ok": 255, "total": 255 }, "percentiles2": { "ko": 0, "ok": 286, "total": 286 }, "percentiles3": { "ko": 0, "ok": 724, "total": 724 }, "percentiles4": { "ko": 0, "ok": 1746, "total": 1746 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 260, "total": 260 } }, { "group1": { "count": 25229, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99 }, "group2": { "count": 299, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 1 }, "group3": { "count": 61, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 1824, "total": 1824 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 13.975423265974877, "total": 13.975423265974877 }, "meanResponseTime": { "ko": 0, "ok": 32, "total": 32 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "ReadManagedUser", "numberOfRequests": { "ko": 0, "ok": 25589, "total": 25589 }, "percentiles1": { "ko": 0, "ok": 6, "total": 6 }, "percentiles2": { "ko": 0, "ok": 7, "total": 7 }, "percentiles3": { "ko": 0, "ok": 15, "total": 14 }, "percentiles4": { "ko": 0, "ok": 921, "total": 921 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 144, "total": 144 } } ], "global": { "group1": { "count": 123233, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 97 }, "group2": { "count": 1823, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 1 }, "group3": { "count": 2148, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 2 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 3170, "total": 3170 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 69.47241944292736, "total": 69.47241944292736 }, "meanResponseTime": { "ko": 0, "ok": 153, "total": 153 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "All Requests", "numberOfRequests": { "ko": 0, "ok": 127204, "total": 127204 }, "percentiles1": { "ko": 0, "ok": 8, "total": 8 }, "percentiles2": { "ko": 0, "ok": 246, "total": 246 }, "percentiles3": { "ko": 0, "ok": 415, "total": 415 }, "percentiles4": { "ko": 0, "ok": 1575, "total": 1574 }, "standardDeviation": { "ko": 0, "ok": 278, "total": 278 } } }, "target_hostname": "xlou.iam.xlou-cdm.engineeringpit.com", "tool_name": "gatling" } ________________________________________________________________________________ [2024-04-24 09:58:28] Reporting-mixedquery-70 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2024-04-24 09:58:28] Reporting-mixedquery-70 step16 : Remove gatling task's stdout file ________________________________________________________________________________ Cleanup of Gatling stdout file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2024-04-24 09:58:28] Reporting-mixedquery-70 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped