--Task-- name: Reporting-mixedquery-30 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'] preceding_task: None options: {'task_name': 'mixedquery-30'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 09:55:55] Reporting-mixedquery-30 pre : N/A ________________________________________________________________________________ source (overseer-0) is a component [2024-04-24 09:55:55] - INFO: Timeout for this Task has changed to 2h (7200 seconds) [2024-04-24 09:55:55] - 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-30/stdout.txt Pod stderr file : /results/orders/Reporting-mixedquery-30/stderr.txt Pod log : /results/orders/Reporting-mixedquery-30 ________________________________________________________________________________ [2024-04-24 09:55:55] Reporting-mixedquery-30 step1 : Write order for overseer to generate the report ________________________________________________________________________________ 2024-04-24 09:55:55,278 INFO 2024-04-24 09:55:55,278 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:55:55,278 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:55,620 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:55,620 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:55,620 DEBUG --- stdout --- 2024-04-24 09:55:55,620 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:55,620 DEBUG --- stderr --- 2024-04-24 09:55:55,620 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/11_Reporting-mixedquery-30/order.json { "name": "Reporting-mixedquery-30", "command": "/lodestar/clients/gatling-report-only-for-pyrock.sh mixedquery-30 --extra-run-jvm-options \"-Xmx12G\"", "out-dir": "/results/orders/Reporting-mixedquery-30" } ________________________________________________________________________________ [2024-04-24 09:55:55] Reporting-mixedquery-30 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2024-04-24 09:55:55,622 INFO 2024-04-24 09:55:55,622 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:55:55,622 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2024-04-24 09:55:55,921 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:55:55,921 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:55,921 DEBUG --- stdout --- 2024-04-24 09:55:55,921 DEBUG WAITING 2024-04-24 09:55:55,922 DEBUG --- stderr --- 2024-04-24 09:55:55,922 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 26 0 --:--:-- --:--:-- --:--:-- 26 ________________________________________________________________________________ [2024-04-24 09:55:55] Reporting-mixedquery-30 step3 : Send order to overseer ________________________________________________________________________________ 2024-04-24 09:55:55,923 INFO 2024-04-24 09:55:55,923 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:55:55,923 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:56,262 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:56,262 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:56,262 DEBUG --- stdout --- 2024-04-24 09:55:56,262 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:56,262 DEBUG --- stderr --- 2024-04-24 09:55:56,262 DEBUG 2024-04-24 09:55:56,263 INFO 2024-04-24 09:55:56,263 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/11_Reporting-mixedquery-30/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 09:55:56,263 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:55:57,674 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:57,674 DEBUG --- stdout --- 2024-04-24 09:55:57,674 DEBUG 2024-04-24 09:55:57,674 DEBUG --- stderr --- 2024-04-24 09:55:57,674 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2024-04-24 09:55:57] Reporting-mixedquery-30 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2024-04-24 09:56:02,679 INFO 2024-04-24 09:56:02,679 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:56:02,679 INFO [loop_until]: (max_time=7200, interval=5, expected_rc=[0] 2024-04-24 09:56:02,924 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2024-04-24 09:56:08,174 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2024-04-24 09:56:08,175 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:08,175 DEBUG --- stdout --- 2024-04-24 09:56:08,175 DEBUG WAITING 2024-04-24 09:56:08,175 DEBUG --- stderr --- 2024-04-24 09:56:08,175 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 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-24 09:56:08] Reporting-mixedquery-30 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2024-04-24 09:56:08,176 INFO 2024-04-24 09:56:08,176 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:56:08,176 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:08,515 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:08,515 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:08,515 DEBUG --- stdout --- 2024-04-24 09:56:08,516 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:08,516 DEBUG --- stderr --- 2024-04-24 09:56:08,516 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:56:08,516 INFO 2024-04-24 09:56:08,516 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:56:08,516 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:08,844 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:08,844 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:08,844 DEBUG --- stdout --- 2024-04-24 09:56:08,844 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:08,844 DEBUG --- stderr --- 2024-04-24 09:56:08,844 DEBUG 2024-04-24 09:56:08,845 INFO 2024-04-24 09:56:08,845 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-30.tar.gz" ]; then echo "/results/gatling/mixedquery-30.tar.gz file found"; exit 0; else echo "/results/gatling/mixedquery-30.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/mixedquery-30.tar.gz file found" 2024-04-24 09:56:08,845 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:56:09,712 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:56:09,712 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:09,712 DEBUG --- stdout --- 2024-04-24 09:56:09,713 DEBUG /results/gatling/mixedquery-30.tar.gz file found 2024-04-24 09:56:09,713 DEBUG --- stderr --- 2024-04-24 09:56:09,713 DEBUG 2024-04-24 09:56:09,713 INFO 2024-04-24 09:56:09,713 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:56:09,713 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:10,051 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:10,052 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:10,052 DEBUG --- stdout --- 2024-04-24 09:56:10,052 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:10,052 DEBUG --- stderr --- 2024-04-24 09:56:10,052 DEBUG 2024-04-24 09:56:10,052 INFO 2024-04-24 09:56:10,052 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-30.tar.gz 2024-04-24 09:56:10,913 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:56:10,914 DEBUG --- stdout --- 2024-04-24 09:56:10,914 DEBUG 340 /results/gatling/mixedquery-30.tar.gz 2024-04-24 09:56:10,914 DEBUG --- stderr --- 2024-04-24 09:56:10,914 DEBUG 2024-04-24 09:56:10,914 INFO - Download file (size 340k) 2024-04-24 09:56:10,914 INFO 2024-04-24 09:56:10,914 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:56:10,914 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:11,244 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:11,244 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:11,244 DEBUG --- stdout --- 2024-04-24 09:56:11,244 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:11,244 DEBUG --- stderr --- 2024-04-24 09:56:11,244 DEBUG source : /results/gatling/mixedquery-30.tar.gz @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-30.tar.gz 2024-04-24 09:56:11,245 INFO 2024-04-24 09:56:11,245 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:11,245 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:11,581 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:11,583 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:11,583 DEBUG --- stdout --- 2024-04-24 09:56:11,583 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:11,583 DEBUG --- stderr --- 2024-04-24 09:56:11,583 DEBUG 2024-04-24 09:56:11,584 INFO 2024-04-24 09:56:11,584 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/gatling/mixedquery-30.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-30.tar.gz 2024-04-24 09:56:11,584 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:56:12,622 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:12,622 DEBUG --- stdout --- 2024-04-24 09:56:12,622 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:56:12,622 DEBUG --- stderr --- 2024-04-24 09:56:12,622 DEBUG ________________________________________________________________________________ [2024-04-24 09:56:12] Reporting-mixedquery-30 step6 : Unzip the result ________________________________________________________________________________ 2024-04-24 09:56:12,624 INFO 2024-04-24 09:56:12,624 INFO [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-30.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/gatling 2024-04-24 09:56:12,680 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:56:12,680 DEBUG --- stdout --- 2024-04-24 09:56:12,681 DEBUG 2024-04-24 09:56:12,681 DEBUG --- stderr --- 2024-04-24 09:56:12,681 DEBUG 2024-04-24 09:56:12,681 INFO ________________________________________________________________________________ [2024-04-24 09:56:12] Reporting-mixedquery-30 step7 : Print rc order ________________________________________________________________________________ 2024-04-24 09:56:12,684 INFO 2024-04-24 09:56:12,684 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:56:12,684 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:13,107 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:13,110 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:13,110 DEBUG --- stdout --- 2024-04-24 09:56:13,110 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:13,110 DEBUG --- stderr --- 2024-04-24 09:56:13,111 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:56:13,111 INFO 2024-04-24 09:56:13,111 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:56:13,111 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:13,504 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:13,504 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:13,504 DEBUG --- stdout --- 2024-04-24 09:56:13,504 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:13,504 DEBUG --- stderr --- 2024-04-24 09:56:13,504 DEBUG 2024-04-24 09:56:13,505 INFO 2024-04-24 09:56:13,505 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-30/rc.txt" ]; then echo "/results/orders/Reporting-mixedquery-30/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-30/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-30/rc.txt file found" 2024-04-24 09:56:13,505 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:56:14,407 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:56:14,407 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:14,407 DEBUG --- stdout --- 2024-04-24 09:56:14,407 DEBUG /results/orders/Reporting-mixedquery-30/rc.txt file found 2024-04-24 09:56:14,407 DEBUG --- stderr --- 2024-04-24 09:56:14,407 DEBUG 2024-04-24 09:56:14,408 INFO 2024-04-24 09:56:14,408 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:56:14,408 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:14,738 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:14,739 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:14,739 DEBUG --- stdout --- 2024-04-24 09:56:14,739 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:14,739 DEBUG --- stderr --- 2024-04-24 09:56:14,739 DEBUG 2024-04-24 09:56:14,739 INFO 2024-04-24 09:56:14,739 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-30/rc.txt 2024-04-24 09:56:15,594 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:56:15,594 DEBUG --- stdout --- 2024-04-24 09:56:15,594 DEBUG 4 /results/orders/Reporting-mixedquery-30/rc.txt 2024-04-24 09:56:15,594 DEBUG --- stderr --- 2024-04-24 09:56:15,594 DEBUG 2024-04-24 09:56:15,594 INFO - Download file (size 4k) 2024-04-24 09:56:15,594 INFO 2024-04-24 09:56:15,595 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:15,595 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:15,921 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:15,921 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:15,921 DEBUG --- stdout --- 2024-04-24 09:56:15,921 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:15,921 DEBUG --- stderr --- 2024-04-24 09:56:15,921 DEBUG source : /results/orders/Reporting-mixedquery-30/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/11_Reporting-mixedquery-30/rc.txt 2024-04-24 09:56:15,922 INFO 2024-04-24 09:56:15,922 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:56:15,922 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:16,254 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:16,254 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:16,254 DEBUG --- stdout --- 2024-04-24 09:56:16,254 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:16,254 DEBUG --- stderr --- 2024-04-24 09:56:16,254 DEBUG 2024-04-24 09:56:16,255 INFO 2024-04-24 09:56:16,255 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-30/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/11_Reporting-mixedquery-30/rc.txt 2024-04-24 09:56:16,255 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:56:17,075 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:17,075 DEBUG --- stdout --- 2024-04-24 09:56:17,075 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:56:17,075 DEBUG --- stderr --- 2024-04-24 09:56:17,075 DEBUG 2024-04-24 09:56:17,075 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/11_Reporting-mixedquery-30/rc.txt is small (1 lines), printing all lines: 2024-04-24 09:56:17,075 DEBUG ----- output ----- 2024-04-24 09:56:17,076 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 09:56:17,076 INFO ________________________________________________________________________________ [2024-04-24 09:56:17] Reporting-mixedquery-30 step8 : Check rc log ________________________________________________________________________________ PASS: no error found in rc log ________________________________________________________________________________ [2024-04-24 09:56:17] Reporting-mixedquery-30 step9 : Print stdout order ________________________________________________________________________________ 2024-04-24 09:56:17,077 INFO 2024-04-24 09:56:17,077 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:56:17,077 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:17,427 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:17,427 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:17,427 DEBUG --- stdout --- 2024-04-24 09:56:17,428 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:17,428 DEBUG --- stderr --- 2024-04-24 09:56:17,428 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:56:17,428 INFO 2024-04-24 09:56:17,428 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:56:17,428 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:17,780 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:17,781 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:17,781 DEBUG --- stdout --- 2024-04-24 09:56:17,781 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:17,781 DEBUG --- stderr --- 2024-04-24 09:56:17,781 DEBUG 2024-04-24 09:56:17,781 INFO 2024-04-24 09:56:17,782 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-30/stdout.txt" ]; then echo "/results/orders/Reporting-mixedquery-30/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-30/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-30/stdout.txt file found" 2024-04-24 09:56:17,782 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:56:18,671 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:56:18,671 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:18,671 DEBUG --- stdout --- 2024-04-24 09:56:18,671 DEBUG /results/orders/Reporting-mixedquery-30/stdout.txt file found 2024-04-24 09:56:18,671 DEBUG --- stderr --- 2024-04-24 09:56:18,671 DEBUG 2024-04-24 09:56:18,672 INFO 2024-04-24 09:56:18,672 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:56:18,672 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:19,007 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:19,007 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:19,008 DEBUG --- stdout --- 2024-04-24 09:56:19,008 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:19,008 DEBUG --- stderr --- 2024-04-24 09:56:19,008 DEBUG 2024-04-24 09:56:19,008 INFO 2024-04-24 09:56:19,008 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-30/stdout.txt 2024-04-24 09:56:19,871 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:56:19,872 DEBUG --- stdout --- 2024-04-24 09:56:19,872 DEBUG 4 /results/orders/Reporting-mixedquery-30/stdout.txt 2024-04-24 09:56:19,872 DEBUG --- stderr --- 2024-04-24 09:56:19,872 DEBUG 2024-04-24 09:56:19,872 INFO - Download file (size 4k) 2024-04-24 09:56:19,872 INFO 2024-04-24 09:56:19,872 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:56:19,872 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:20,276 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:20,276 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:20,276 DEBUG --- stdout --- 2024-04-24 09:56:20,276 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:20,277 DEBUG --- stderr --- 2024-04-24 09:56:20,277 DEBUG source : /results/orders/Reporting-mixedquery-30/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/11_Reporting-mixedquery-30/stdout.txt 2024-04-24 09:56:20,277 INFO 2024-04-24 09:56:20,277 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:56:20,277 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:20,611 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:20,612 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:20,612 DEBUG --- stdout --- 2024-04-24 09:56:20,612 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:20,612 DEBUG --- stderr --- 2024-04-24 09:56:20,612 DEBUG 2024-04-24 09:56:20,612 INFO 2024-04-24 09:56:20,612 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-30/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/11_Reporting-mixedquery-30/stdout.txt 2024-04-24 09:56:20,612 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:56:21,409 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:21,413 DEBUG --- stdout --- 2024-04-24 09:56:21,413 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:56:21,413 DEBUG --- stderr --- 2024-04-24 09:56:21,413 DEBUG 2024-04-24 09:56:21,414 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/11_Reporting-mixedquery-30/stdout.txt is small (81 lines), printing all lines: 2024-04-24 09:56:21,414 DEBUG ----- output ----- 2024-04-24 09:56:21,414 DEBUG [cmd] /lodestar/clients/gatling/bin/gatling.sh --reports-only /results/gatling/mixedquery-30/loadtest-20240424063856741 --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 54475 (OK=54475 KO=0 ) > min response time 5 (OK=5 KO=- ) > max response time 345 (OK=345 KO=- ) > mean response time 94 (OK=94 KO=- ) > std deviation 108 (OK=108 KO=- ) > response time 50th percentile 8 (OK=8 KO=- ) > response time 75th percentile 216 (OK=216 KO=- ) > response time 95th percentile 253 (OK=253 KO=- ) > response time 99th percentile 276 (OK=276 KO=- ) > mean requests/sec 29.768 (OK=29.768 KO=- ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 54475 (100%) > 800 ms <= t < 1200 ms 0 ( 0%) > t >= 1200 ms 0 ( 0%) > failed 0 ( 0%) ================================================================================ Reports generated in 0s. Please open the following file: file:///results/gatling/mixedquery-30/loadtest-20240424063856741/index.html ---------------------- [24/Apr/2024 10:56:04] Create tar file /results/gatling/mixedquery-30.tar.gz ---------------------- SIMULATION_PATH=/results/gatling/mixedquery-30/loadtest-20240424063856741 RESULT_FOLDER=mixedquery-30 RESULT_PATH=/results/gatling/mixedquery-30 PWD=/results/gatling/mixedquery-30/loadtest-20240424063856741 [cmd] tar -czvf /results/gatling/mixedquery-30.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:56:21,414 INFO ________________________________________________________________________________ [2024-04-24 09:56:21] Reporting-mixedquery-30 step10 : Check stdout log ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2024-04-24 09:56:21] Reporting-mixedquery-30 step11 : Print stderr order ________________________________________________________________________________ 2024-04-24 09:56:21,417 INFO 2024-04-24 09:56:21,417 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:56:21,417 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:21,760 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:21,760 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:21,760 DEBUG --- stdout --- 2024-04-24 09:56:21,760 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:21,760 DEBUG --- stderr --- 2024-04-24 09:56:21,760 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:56:21,760 INFO 2024-04-24 09:56:21,761 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:56:21,761 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:22,083 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:22,083 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:22,083 DEBUG --- stdout --- 2024-04-24 09:56:22,083 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:22,083 DEBUG --- stderr --- 2024-04-24 09:56:22,083 DEBUG 2024-04-24 09:56:22,084 INFO 2024-04-24 09:56:22,084 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-30/stderr.txt" ]; then echo "/results/orders/Reporting-mixedquery-30/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-30/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-30/stderr.txt file found" 2024-04-24 09:56:22,084 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:56:22,930 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:56:22,930 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:22,930 DEBUG --- stdout --- 2024-04-24 09:56:22,930 DEBUG /results/orders/Reporting-mixedquery-30/stderr.txt file found 2024-04-24 09:56:22,930 DEBUG --- stderr --- 2024-04-24 09:56:22,930 DEBUG 2024-04-24 09:56:22,930 INFO 2024-04-24 09:56:22,931 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:56:22,931 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:23,258 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:23,258 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:23,258 DEBUG --- stdout --- 2024-04-24 09:56:23,259 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:23,259 DEBUG --- stderr --- 2024-04-24 09:56:23,259 DEBUG 2024-04-24 09:56:23,259 INFO 2024-04-24 09:56:23,259 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-30/stderr.txt 2024-04-24 09:56:24,142 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:56:24,144 DEBUG --- stdout --- 2024-04-24 09:56:24,144 DEBUG 0 /results/orders/Reporting-mixedquery-30/stderr.txt 2024-04-24 09:56:24,145 DEBUG --- stderr --- 2024-04-24 09:56:24,145 DEBUG 2024-04-24 09:56:24,145 INFO - Download file (size 0k) 2024-04-24 09:56:24,146 INFO 2024-04-24 09:56:24,146 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:56:24,146 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:24,529 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:24,530 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:24,530 DEBUG --- stdout --- 2024-04-24 09:56:24,530 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:24,530 DEBUG --- stderr --- 2024-04-24 09:56:24,530 DEBUG source : /results/orders/Reporting-mixedquery-30/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/11_Reporting-mixedquery-30/stderr.txt 2024-04-24 09:56:24,531 INFO 2024-04-24 09:56:24,531 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:56:24,531 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:24,900 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:24,901 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:24,901 DEBUG --- stdout --- 2024-04-24 09:56:24,901 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:24,901 DEBUG --- stderr --- 2024-04-24 09:56:24,901 DEBUG 2024-04-24 09:56:24,901 INFO 2024-04-24 09:56:24,902 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-30/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/11_Reporting-mixedquery-30/stderr.txt 2024-04-24 09:56:24,902 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:56:25,669 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:25,670 DEBUG --- stdout --- 2024-04-24 09:56:25,670 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:56:25,670 DEBUG --- stderr --- 2024-04-24 09:56:25,670 DEBUG 2024-04-24 09:56:25,670 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/11_Reporting-mixedquery-30/stderr.txt is small (0 lines), printing all lines: 2024-04-24 09:56:25,671 DEBUG ----- output ----- 2024-04-24 09:56:25,671 DEBUG 2024-04-24 09:56:25,671 INFO ________________________________________________________________________________ [2024-04-24 09:56:25] Reporting-mixedquery-30 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-24 09:56:25] Reporting-mixedquery-30 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 ReadManagedUser checking simulation request queryBySn checking simulation request queryStartsWith checking simulation request queryPartial checking simulation request queryFull no spikes found ________________________________________________________________________________ [2024-04-24 09:56:25] Reporting-mixedquery-30 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 54475, "ok": 54475, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 345, "ok": 345, "ko": 0 }, "meanResponseTime": { "total": 94, "ok": 94, "ko": 0 }, "standardDeviation": { "total": 108, "ok": 108, "ko": 0 }, "percentiles1": { "total": 8, "ok": 8, "ko": 0 }, "percentiles2": { "total": 216, "ok": 216, "ko": 0 }, "percentiles3": { "total": 253, "ok": 253, "ko": 0 }, "percentiles4": { "total": 276, "ok": 276, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 54475, "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": 29.76775956284153, "ok": 29.76775956284153, "ko": 0 } }, "details": [ { "name": "AdminRestLogin", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 25, "ok": 25, "ko": 0 }, "maxResponseTime": { "total": 25, "ok": 25, "ko": 0 }, "meanResponseTime": { "total": 25, "ok": 25, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 25, "ok": 25, "ko": 0 }, "percentiles2": { "total": 25, "ok": 25, "ko": 0 }, "percentiles3": { "total": 25, "ok": 25, "ko": 0 }, "percentiles4": { "total": 25, "ok": 25, "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.000546448087431694, "ok": 0.000546448087431694, "ko": 0 }, "spikes": [] }, { "name": "AdminAuthorize", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 13, "ok": 13, "ko": 0 }, "maxResponseTime": { "total": 13, "ok": 13, "ko": 0 }, "meanResponseTime": { "total": 13, "ok": 13, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 13, "ok": 13, "ko": 0 }, "percentiles2": { "total": 13, "ok": 13, "ko": 0 }, "percentiles3": { "total": 13, "ok": 13, "ko": 0 }, "percentiles4": { "total": 13, "ok": 13, "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.000546448087431694, "ok": 0.000546448087431694, "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.000546448087431694, "ok": 0.000546448087431694, "ko": 0 }, "spikes": [] }, { "name": "ReadManagedUser", "numberOfRequests": { "total": 10703, "ok": 10703, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 58, "ok": 58, "ko": 0 }, "meanResponseTime": { "total": 6, "ok": 6, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 6, "ok": 6, "ko": 0 }, "percentiles2": { "total": 7, "ok": 7, "ko": 0 }, "percentiles3": { "total": 7, "ok": 7, "ko": 0 }, "percentiles4": { "total": 9, "ok": 9, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 10703, "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": 5.848633879781421, "ok": 5.848633879781421, "ko": 0 }, "spikes": [] }, { "name": "queryBySn", "numberOfRequests": { "total": 11071, "ok": 11071, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 59, "ok": 59, "ko": 0 }, "meanResponseTime": { "total": 7, "ok": 7, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 7, "ok": 7, "ko": 0 }, "percentiles2": { "total": 8, "ok": 8, "ko": 0 }, "percentiles3": { "total": 9, "ok": 9, "ko": 0 }, "percentiles4": { "total": 11, "ok": 11, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 11071, "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": 6.049726775956284, "ok": 6.049726775956284, "ko": 0 }, "spikes": [] }, { "name": "queryStartsWith", "numberOfRequests": { "total": 11001, "ok": 11001, "ko": 0 }, "minResponseTime": { "total": 85, "ok": 85, "ko": 0 }, "maxResponseTime": { "total": 328, "ok": 328, "ko": 0 }, "meanResponseTime": { "total": 228, "ok": 228, "ko": 0 }, "standardDeviation": { "total": 31, "ok": 31, "ko": 0 }, "percentiles1": { "total": 230, "ok": 230, "ko": 0 }, "percentiles2": { "total": 245, "ok": 245, "ko": 0 }, "percentiles3": { "total": 269, "ok": 269, "ko": 0 }, "percentiles4": { "total": 288, "ok": 288, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 11001, "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": 6.011475409836065, "ok": 6.011475409836065, "ko": 0 }, "spikes": [] }, { "name": "queryPartial", "numberOfRequests": { "total": 10816, "ok": 10816, "ko": 0 }, "minResponseTime": { "total": 162, "ok": 162, "ko": 0 }, "maxResponseTime": { "total": 345, "ok": 345, "ko": 0 }, "meanResponseTime": { "total": 220, "ok": 220, "ko": 0 }, "standardDeviation": { "total": 23, "ok": 23, "ko": 0 }, "percentiles1": { "total": 217, "ok": 217, "ko": 0 }, "percentiles2": { "total": 234, "ok": 234, "ko": 0 }, "percentiles3": { "total": 262, "ok": 262, "ko": 0 }, "percentiles4": { "total": 284, "ok": 284, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 10816, "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": 5.910382513661202, "ok": 5.910382513661202, "ko": 0 }, "spikes": [] }, { "name": "queryFull", "numberOfRequests": { "total": 10881, "ok": 10881, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "meanResponseTime": { "total": 7, "ok": 7, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 7, "ok": 7, "ko": 0 }, "percentiles2": { "total": 7, "ok": 7, "ko": 0 }, "percentiles3": { "total": 8, "ok": 8, "ko": 0 }, "percentiles4": { "total": 11, "ok": 11, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 10881, "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": 5.945901639344262, "ok": 5.945901639344262, "ko": 0 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 30, "avg_response_time": 94, "max_response_time": 345, "min_response_time": 5, "num_requests": 54475, "num_requests_pass": 54475, "num_requests_percent_pass": 100.0 }, "options": "; duration:1800; simulation:com.forgerock.gatling.Loadtest; testCase:idm.MixedQuery; testMode:open; throughput:30", "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": 25, "total": 25 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.000546448087431694, "total": 0.000546448087431694 }, "meanResponseTime": { "ko": 0, "ok": 25, "total": 25 }, "minResponseTime": { "ko": 0, "ok": 25, "total": 25 }, "name": "AdminRestLogin", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 25, "total": 25 }, "percentiles2": { "ko": 0, "ok": 25, "total": 25 }, "percentiles3": { "ko": 0, "ok": 25, "total": 25 }, "percentiles4": { "ko": 0, "ok": 25, "total": 25 }, "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": 13, "total": 13 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.000546448087431694, "total": 0.000546448087431694 }, "meanResponseTime": { "ko": 0, "ok": 13, "total": 13 }, "minResponseTime": { "ko": 0, "ok": 13, "total": 13 }, "name": "AdminAuthorize", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 13, "total": 13 }, "percentiles2": { "ko": 0, "ok": 13, "total": 13 }, "percentiles3": { "ko": 0, "ok": 13, "total": 13 }, "percentiles4": { "ko": 0, "ok": 13, "total": 13 }, "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.000546448087431694, "total": 0.000546448087431694 }, "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": 10703, "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": 58, "total": 58 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 5.848633879781421, "total": 5.848633879781421 }, "meanResponseTime": { "ko": 0, "ok": 6, "total": 6 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "ReadManagedUser", "numberOfRequests": { "ko": 0, "ok": 10703, "total": 10703 }, "percentiles1": { "ko": 0, "ok": 6, "total": 6 }, "percentiles2": { "ko": 0, "ok": 7, "total": 7 }, "percentiles3": { "ko": 0, "ok": 7, "total": 7 }, "percentiles4": { "ko": 0, "ok": 9, "total": 9 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } }, { "group1": { "count": 11071, "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": 59, "total": 59 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 6.049726775956284, "total": 6.049726775956284 }, "meanResponseTime": { "ko": 0, "ok": 7, "total": 7 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryBySn", "numberOfRequests": { "ko": 0, "ok": 11071, "total": 11071 }, "percentiles1": { "ko": 0, "ok": 7, "total": 7 }, "percentiles2": { "ko": 0, "ok": 8, "total": 8 }, "percentiles3": { "ko": 0, "ok": 9, "total": 9 }, "percentiles4": { "ko": 0, "ok": 11, "total": 11 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } }, { "group1": { "count": 11001, "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": 328, "total": 328 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 6.011475409836065, "total": 6.011475409836065 }, "meanResponseTime": { "ko": 0, "ok": 228, "total": 228 }, "minResponseTime": { "ko": 0, "ok": 85, "total": 85 }, "name": "queryStartsWith", "numberOfRequests": { "ko": 0, "ok": 11001, "total": 11001 }, "percentiles1": { "ko": 0, "ok": 230, "total": 230 }, "percentiles2": { "ko": 0, "ok": 245, "total": 245 }, "percentiles3": { "ko": 0, "ok": 269, "total": 269 }, "percentiles4": { "ko": 0, "ok": 288, "total": 288 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 31, "total": 31 } }, { "group1": { "count": 10816, "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": 345, "total": 345 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 5.910382513661202, "total": 5.910382513661202 }, "meanResponseTime": { "ko": 0, "ok": 220, "total": 220 }, "minResponseTime": { "ko": 0, "ok": 162, "total": 162 }, "name": "queryPartial", "numberOfRequests": { "ko": 0, "ok": 10816, "total": 10816 }, "percentiles1": { "ko": 0, "ok": 217, "total": 217 }, "percentiles2": { "ko": 0, "ok": 234, "total": 234 }, "percentiles3": { "ko": 0, "ok": 262, "total": 262 }, "percentiles4": { "ko": 0, "ok": 284, "total": 284 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 23, "total": 23 } }, { "group1": { "count": 10881, "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": 63, "total": 63 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 5.945901639344262, "total": 5.945901639344262 }, "meanResponseTime": { "ko": 0, "ok": 7, "total": 7 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryFull", "numberOfRequests": { "ko": 0, "ok": 10881, "total": 10881 }, "percentiles1": { "ko": 0, "ok": 7, "total": 7 }, "percentiles2": { "ko": 0, "ok": 7, "total": 7 }, "percentiles3": { "ko": 0, "ok": 8, "total": 8 }, "percentiles4": { "ko": 0, "ok": 11, "total": 11 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } } ], "global": { "group1": { "count": 54475, "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": 345, "total": 345 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 29.76775956284153, "total": 29.76775956284153 }, "meanResponseTime": { "ko": 0, "ok": 94, "total": 94 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "All Requests", "numberOfRequests": { "ko": 0, "ok": 54475, "total": 54475 }, "percentiles1": { "ko": 0, "ok": 8, "total": 8 }, "percentiles2": { "ko": 0, "ok": 216, "total": 216 }, "percentiles3": { "ko": 0, "ok": 253, "total": 253 }, "percentiles4": { "ko": 0, "ok": 276, "total": 276 }, "standardDeviation": { "ko": 0, "ok": 108, "total": 108 } } }, "target_hostname": "xlou.iam.xlou-cdm.engineeringpit.com", "tool_name": "gatling" } ________________________________________________________________________________ [2024-04-24 09:56:25] Reporting-mixedquery-30 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2024-04-24 09:56:25] Reporting-mixedquery-30 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/03_mixedquery-30/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2024-04-24 09:56:25] Reporting-mixedquery-30 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped