--Task-- name: Reporting-mixedquery-50 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'] preceding_task: None options: {'task_name': 'mixedquery-50'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 09:56:56] Reporting-mixedquery-50 pre : N/A ________________________________________________________________________________ source (overseer-0) is a component [2024-04-24 09:56:56] - INFO: Timeout for this Task has changed to 2h (7200 seconds) [2024-04-24 09:56:56] - 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-50/stdout.txt Pod stderr file : /results/orders/Reporting-mixedquery-50/stderr.txt Pod log : /results/orders/Reporting-mixedquery-50 ________________________________________________________________________________ [2024-04-24 09:56:56] Reporting-mixedquery-50 step1 : Write order for overseer to generate the report ________________________________________________________________________________ 2024-04-24 09:56:56,811 INFO 2024-04-24 09:56:56,812 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:56,812 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:57,163 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:57,163 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:57,163 DEBUG --- stdout --- 2024-04-24 09:56:57,163 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:57,163 DEBUG --- stderr --- 2024-04-24 09:56:57,163 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/13_Reporting-mixedquery-50/order.json { "name": "Reporting-mixedquery-50", "command": "/lodestar/clients/gatling-report-only-for-pyrock.sh mixedquery-50 --extra-run-jvm-options \"-Xmx12G\"", "out-dir": "/results/orders/Reporting-mixedquery-50" } ________________________________________________________________________________ [2024-04-24 09:56:57] Reporting-mixedquery-50 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2024-04-24 09:56:57,165 INFO 2024-04-24 09:56:57,165 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:56:57,165 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2024-04-24 09:56:57,451 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:56:57,451 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:57,451 DEBUG --- stdout --- 2024-04-24 09:56:57,451 DEBUG WAITING 2024-04-24 09:56:57,451 DEBUG --- stderr --- 2024-04-24 09:56:57,451 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 27 0 --:--:-- --:--:-- --:--:-- 27 ________________________________________________________________________________ [2024-04-24 09:56:57] Reporting-mixedquery-50 step3 : Send order to overseer ________________________________________________________________________________ 2024-04-24 09:56:57,453 INFO 2024-04-24 09:56:57,453 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:57,453 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:57,788 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:57,789 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:57,789 DEBUG --- stdout --- 2024-04-24 09:56:57,789 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:57,789 DEBUG --- stderr --- 2024-04-24 09:56:57,789 DEBUG 2024-04-24 09:56:57,790 INFO 2024-04-24 09:56:57,790 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/13_Reporting-mixedquery-50/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 09:56:57,790 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:56:59,205 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:59,205 DEBUG --- stdout --- 2024-04-24 09:56:59,205 DEBUG 2024-04-24 09:56:59,206 DEBUG --- stderr --- 2024-04-24 09:56:59,206 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2024-04-24 09:56:59] Reporting-mixedquery-50 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2024-04-24 09:57:04,211 INFO 2024-04-24 09:57:04,211 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:57:04,211 INFO [loop_until]: (max_time=7200, interval=5, expected_rc=[0] 2024-04-24 09:57:04,454 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2024-04-24 09:57:09,711 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2024-04-24 09:57:09,712 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:09,712 DEBUG --- stdout --- 2024-04-24 09:57:09,712 DEBUG WAITING 2024-04-24 09:57:09,712 DEBUG --- stderr --- 2024-04-24 09:57:09,712 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 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-24 09:57:09] Reporting-mixedquery-50 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2024-04-24 09:57:09,714 INFO 2024-04-24 09:57:09,714 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:09,714 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:10,069 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:10,070 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:10,070 DEBUG --- stdout --- 2024-04-24 09:57:10,070 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:10,070 DEBUG --- stderr --- 2024-04-24 09:57:10,070 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:57:10,070 INFO 2024-04-24 09:57:10,070 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:10,070 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:10,396 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:10,396 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:10,396 DEBUG --- stdout --- 2024-04-24 09:57:10,396 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:10,396 DEBUG --- stderr --- 2024-04-24 09:57:10,396 DEBUG 2024-04-24 09:57:10,397 INFO 2024-04-24 09:57:10,397 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-50.tar.gz" ]; then echo "/results/gatling/mixedquery-50.tar.gz file found"; exit 0; else echo "/results/gatling/mixedquery-50.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/mixedquery-50.tar.gz file found" 2024-04-24 09:57:10,397 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:57:11,227 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:57:11,228 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:11,228 DEBUG --- stdout --- 2024-04-24 09:57:11,228 DEBUG /results/gatling/mixedquery-50.tar.gz file found 2024-04-24 09:57:11,228 DEBUG --- stderr --- 2024-04-24 09:57:11,228 DEBUG 2024-04-24 09:57:11,228 INFO 2024-04-24 09:57:11,228 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:11,228 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:11,550 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:11,550 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:11,550 DEBUG --- stdout --- 2024-04-24 09:57:11,550 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:11,550 DEBUG --- stderr --- 2024-04-24 09:57:11,550 DEBUG 2024-04-24 09:57:11,551 INFO 2024-04-24 09:57:11,551 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-50.tar.gz 2024-04-24 09:57:12,397 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:57:12,398 DEBUG --- stdout --- 2024-04-24 09:57:12,398 DEBUG 344 /results/gatling/mixedquery-50.tar.gz 2024-04-24 09:57:12,398 DEBUG --- stderr --- 2024-04-24 09:57:12,398 DEBUG 2024-04-24 09:57:12,398 INFO - Download file (size 344k) 2024-04-24 09:57:12,399 INFO 2024-04-24 09:57:12,399 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:12,399 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:12,778 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:12,778 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:12,778 DEBUG --- stdout --- 2024-04-24 09:57:12,778 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:12,778 DEBUG --- stderr --- 2024-04-24 09:57:12,778 DEBUG source : /results/gatling/mixedquery-50.tar.gz @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-50.tar.gz 2024-04-24 09:57:12,778 INFO 2024-04-24 09:57:12,778 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:12,778 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:13,165 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:13,165 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:13,165 DEBUG --- stdout --- 2024-04-24 09:57:13,165 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:13,165 DEBUG --- stderr --- 2024-04-24 09:57:13,165 DEBUG 2024-04-24 09:57:13,166 INFO 2024-04-24 09:57:13,166 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/gatling/mixedquery-50.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-50.tar.gz 2024-04-24 09:57:13,166 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:57:14,214 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:14,215 DEBUG --- stdout --- 2024-04-24 09:57:14,216 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:57:14,216 DEBUG --- stderr --- 2024-04-24 09:57:14,216 DEBUG ________________________________________________________________________________ [2024-04-24 09:57:14] Reporting-mixedquery-50 step6 : Unzip the result ________________________________________________________________________________ 2024-04-24 09:57:14,217 INFO 2024-04-24 09:57:14,218 INFO [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-50.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/gatling 2024-04-24 09:57:14,264 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:57:14,265 DEBUG --- stdout --- 2024-04-24 09:57:14,265 DEBUG 2024-04-24 09:57:14,265 DEBUG --- stderr --- 2024-04-24 09:57:14,265 DEBUG 2024-04-24 09:57:14,265 INFO ________________________________________________________________________________ [2024-04-24 09:57:14] Reporting-mixedquery-50 step7 : Print rc order ________________________________________________________________________________ 2024-04-24 09:57:14,266 INFO 2024-04-24 09:57:14,266 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:14,266 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:14,645 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:14,645 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:14,645 DEBUG --- stdout --- 2024-04-24 09:57:14,645 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:14,645 DEBUG --- stderr --- 2024-04-24 09:57:14,645 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:57:14,646 INFO 2024-04-24 09:57:14,646 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:14,646 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:15,038 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:15,038 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:15,039 DEBUG --- stdout --- 2024-04-24 09:57:15,039 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:15,039 DEBUG --- stderr --- 2024-04-24 09:57:15,039 DEBUG 2024-04-24 09:57:15,039 INFO 2024-04-24 09:57:15,039 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-50/rc.txt" ]; then echo "/results/orders/Reporting-mixedquery-50/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-50/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-50/rc.txt file found" 2024-04-24 09:57:15,040 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:57:15,900 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:57:15,901 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:15,901 DEBUG --- stdout --- 2024-04-24 09:57:15,901 DEBUG /results/orders/Reporting-mixedquery-50/rc.txt file found 2024-04-24 09:57:15,901 DEBUG --- stderr --- 2024-04-24 09:57:15,901 DEBUG 2024-04-24 09:57:15,901 INFO 2024-04-24 09:57:15,901 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:15,901 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:16,226 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:16,226 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:16,226 DEBUG --- stdout --- 2024-04-24 09:57:16,226 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:16,226 DEBUG --- stderr --- 2024-04-24 09:57:16,226 DEBUG 2024-04-24 09:57:16,226 INFO 2024-04-24 09:57:16,227 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-50/rc.txt 2024-04-24 09:57:17,100 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:57:17,101 DEBUG --- stdout --- 2024-04-24 09:57:17,101 DEBUG 4 /results/orders/Reporting-mixedquery-50/rc.txt 2024-04-24 09:57:17,101 DEBUG --- stderr --- 2024-04-24 09:57:17,101 DEBUG 2024-04-24 09:57:17,101 INFO - Download file (size 4k) 2024-04-24 09:57:17,101 INFO 2024-04-24 09:57:17,101 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:17,101 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:17,437 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:17,438 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:17,438 DEBUG --- stdout --- 2024-04-24 09:57:17,438 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:17,438 DEBUG --- stderr --- 2024-04-24 09:57:17,438 DEBUG source : /results/orders/Reporting-mixedquery-50/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/13_Reporting-mixedquery-50/rc.txt 2024-04-24 09:57:17,438 INFO 2024-04-24 09:57:17,438 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:17,438 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:17,765 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:17,765 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:17,765 DEBUG --- stdout --- 2024-04-24 09:57:17,765 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:17,765 DEBUG --- stderr --- 2024-04-24 09:57:17,765 DEBUG 2024-04-24 09:57:17,766 INFO 2024-04-24 09:57:17,766 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-50/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/13_Reporting-mixedquery-50/rc.txt 2024-04-24 09:57:17,766 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:57:18,552 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:18,553 DEBUG --- stdout --- 2024-04-24 09:57:18,553 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:57:18,553 DEBUG --- stderr --- 2024-04-24 09:57:18,553 DEBUG 2024-04-24 09:57:18,554 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/13_Reporting-mixedquery-50/rc.txt is small (1 lines), printing all lines: 2024-04-24 09:57:18,554 DEBUG ----- output ----- 2024-04-24 09:57:18,554 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 09:57:18,554 INFO ________________________________________________________________________________ [2024-04-24 09:57:18] Reporting-mixedquery-50 step8 : Check rc log ________________________________________________________________________________ PASS: no error found in rc log ________________________________________________________________________________ [2024-04-24 09:57:18] Reporting-mixedquery-50 step9 : Print stdout order ________________________________________________________________________________ 2024-04-24 09:57:18,556 INFO 2024-04-24 09:57:18,556 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:18,556 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:18,945 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:18,946 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:18,946 DEBUG --- stdout --- 2024-04-24 09:57:18,946 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:18,946 DEBUG --- stderr --- 2024-04-24 09:57:18,947 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:57:18,947 INFO 2024-04-24 09:57:18,947 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:18,947 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:19,365 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:19,365 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:19,365 DEBUG --- stdout --- 2024-04-24 09:57:19,365 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:19,365 DEBUG --- stderr --- 2024-04-24 09:57:19,365 DEBUG 2024-04-24 09:57:19,366 INFO 2024-04-24 09:57:19,366 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-50/stdout.txt" ]; then echo "/results/orders/Reporting-mixedquery-50/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-50/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-50/stdout.txt file found" 2024-04-24 09:57:19,366 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:57:20,213 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:57:20,215 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:20,215 DEBUG --- stdout --- 2024-04-24 09:57:20,215 DEBUG /results/orders/Reporting-mixedquery-50/stdout.txt file found 2024-04-24 09:57:20,215 DEBUG --- stderr --- 2024-04-24 09:57:20,215 DEBUG 2024-04-24 09:57:20,216 INFO 2024-04-24 09:57:20,216 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:20,216 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:20,604 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:20,605 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:20,605 DEBUG --- stdout --- 2024-04-24 09:57:20,605 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:20,606 DEBUG --- stderr --- 2024-04-24 09:57:20,606 DEBUG 2024-04-24 09:57:20,606 INFO 2024-04-24 09:57:20,607 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-50/stdout.txt 2024-04-24 09:57:21,506 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:57:21,506 DEBUG --- stdout --- 2024-04-24 09:57:21,506 DEBUG 4 /results/orders/Reporting-mixedquery-50/stdout.txt 2024-04-24 09:57:21,506 DEBUG --- stderr --- 2024-04-24 09:57:21,506 DEBUG 2024-04-24 09:57:21,506 INFO - Download file (size 4k) 2024-04-24 09:57:21,506 INFO 2024-04-24 09:57:21,506 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:21,507 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:21,828 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:21,829 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:21,829 DEBUG --- stdout --- 2024-04-24 09:57:21,829 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:21,829 DEBUG --- stderr --- 2024-04-24 09:57:21,829 DEBUG source : /results/orders/Reporting-mixedquery-50/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/13_Reporting-mixedquery-50/stdout.txt 2024-04-24 09:57:21,829 INFO 2024-04-24 09:57:21,829 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:21,829 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:22,168 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:22,168 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:22,168 DEBUG --- stdout --- 2024-04-24 09:57:22,168 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:22,168 DEBUG --- stderr --- 2024-04-24 09:57:22,168 DEBUG 2024-04-24 09:57:22,169 INFO 2024-04-24 09:57:22,169 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-50/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/13_Reporting-mixedquery-50/stdout.txt 2024-04-24 09:57:22,169 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:57:22,930 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:22,931 DEBUG --- stdout --- 2024-04-24 09:57:22,931 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:57:22,931 DEBUG --- stderr --- 2024-04-24 09:57:22,931 DEBUG 2024-04-24 09:57:22,931 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/13_Reporting-mixedquery-50/stdout.txt is small (81 lines), printing all lines: 2024-04-24 09:57:22,931 DEBUG ----- output ----- 2024-04-24 09:57:22,931 DEBUG [cmd] /lodestar/clients/gatling/bin/gatling.sh --reports-only /results/gatling/mixedquery-50/loadtest-20240424074405943 --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 90643 (OK=90643 KO=0 ) > min response time 5 (OK=5 KO=- ) > max response time 538 (OK=538 KO=- ) > mean response time 99 (OK=99 KO=- ) > std deviation 114 (OK=114 KO=- ) > response time 50th percentile 8 (OK=8 KO=- ) > response time 75th percentile 228 (OK=228 KO=- ) > response time 95th percentile 268 (OK=268 KO=- ) > response time 99th percentile 295 (OK=295 KO=- ) > mean requests/sec 49.505 (OK=49.505 KO=- ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 90643 (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-50/loadtest-20240424074405943/index.html ---------------------- [24/Apr/2024 10:57:07] Create tar file /results/gatling/mixedquery-50.tar.gz ---------------------- SIMULATION_PATH=/results/gatling/mixedquery-50/loadtest-20240424074405943 RESULT_FOLDER=mixedquery-50 RESULT_PATH=/results/gatling/mixedquery-50 PWD=/results/gatling/mixedquery-50/loadtest-20240424074405943 [cmd] tar -czvf /results/gatling/mixedquery-50.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:57:22,931 INFO ________________________________________________________________________________ [2024-04-24 09:57:22] Reporting-mixedquery-50 step10 : Check stdout log ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2024-04-24 09:57:22] Reporting-mixedquery-50 step11 : Print stderr order ________________________________________________________________________________ 2024-04-24 09:57:22,934 INFO 2024-04-24 09:57:22,934 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:22,934 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:23,281 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:23,281 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:23,281 DEBUG --- stdout --- 2024-04-24 09:57:23,281 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:23,281 DEBUG --- stderr --- 2024-04-24 09:57:23,281 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:57:23,282 INFO 2024-04-24 09:57:23,282 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:23,282 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:23,612 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:23,613 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:23,613 DEBUG --- stdout --- 2024-04-24 09:57:23,613 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:23,613 DEBUG --- stderr --- 2024-04-24 09:57:23,613 DEBUG 2024-04-24 09:57:23,614 INFO 2024-04-24 09:57:23,614 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-50/stderr.txt" ]; then echo "/results/orders/Reporting-mixedquery-50/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-50/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-50/stderr.txt file found" 2024-04-24 09:57:23,614 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:57:24,450 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:57:24,450 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:24,450 DEBUG --- stdout --- 2024-04-24 09:57:24,450 DEBUG /results/orders/Reporting-mixedquery-50/stderr.txt file found 2024-04-24 09:57:24,451 DEBUG --- stderr --- 2024-04-24 09:57:24,451 DEBUG 2024-04-24 09:57:24,451 INFO 2024-04-24 09:57:24,451 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:24,451 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:24,794 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:24,794 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:24,794 DEBUG --- stdout --- 2024-04-24 09:57:24,794 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:24,794 DEBUG --- stderr --- 2024-04-24 09:57:24,794 DEBUG 2024-04-24 09:57:24,794 INFO 2024-04-24 09:57:24,795 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-50/stderr.txt 2024-04-24 09:57:25,711 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:57:25,711 DEBUG --- stdout --- 2024-04-24 09:57:25,711 DEBUG 0 /results/orders/Reporting-mixedquery-50/stderr.txt 2024-04-24 09:57:25,711 DEBUG --- stderr --- 2024-04-24 09:57:25,711 DEBUG 2024-04-24 09:57:25,711 INFO - Download file (size 0k) 2024-04-24 09:57:25,712 INFO 2024-04-24 09:57:25,712 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:25,712 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:26,042 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:26,042 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:26,042 DEBUG --- stdout --- 2024-04-24 09:57:26,042 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:26,042 DEBUG --- stderr --- 2024-04-24 09:57:26,042 DEBUG source : /results/orders/Reporting-mixedquery-50/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/13_Reporting-mixedquery-50/stderr.txt 2024-04-24 09:57:26,043 INFO 2024-04-24 09:57:26,043 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:26,043 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:26,396 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:26,396 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:26,396 DEBUG --- stdout --- 2024-04-24 09:57:26,396 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:26,397 DEBUG --- stderr --- 2024-04-24 09:57:26,397 DEBUG 2024-04-24 09:57:26,398 INFO 2024-04-24 09:57:26,398 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-50/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/13_Reporting-mixedquery-50/stderr.txt 2024-04-24 09:57:26,398 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:57:27,183 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:27,184 DEBUG --- stdout --- 2024-04-24 09:57:27,184 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:57:27,184 DEBUG --- stderr --- 2024-04-24 09:57:27,184 DEBUG 2024-04-24 09:57:27,184 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/13_Reporting-mixedquery-50/stderr.txt is small (0 lines), printing all lines: 2024-04-24 09:57:27,184 DEBUG ----- output ----- 2024-04-24 09:57:27,184 DEBUG 2024-04-24 09:57:27,184 INFO ________________________________________________________________________________ [2024-04-24 09:57:27] Reporting-mixedquery-50 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-24 09:57:27] Reporting-mixedquery-50 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 queryFull checking simulation request queryStartsWith checking simulation request queryPartial no spikes found ________________________________________________________________________________ [2024-04-24 09:57:27] Reporting-mixedquery-50 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 90643, "ok": 90643, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 538, "ok": 538, "ko": 0 }, "meanResponseTime": { "total": 99, "ok": 99, "ko": 0 }, "standardDeviation": { "total": 114, "ok": 114, "ko": 0 }, "percentiles1": { "total": 8, "ok": 8, "ko": 0 }, "percentiles2": { "total": 228, "ok": 228, "ko": 0 }, "percentiles3": { "total": 268, "ok": 268, "ko": 0 }, "percentiles4": { "total": 295, "ok": 295, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 90643, "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": 49.504642271982526, "ok": 49.504642271982526, "ko": 0 } }, "details": [ { "name": "AdminRestLogin", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 24, "ok": 24, "ko": 0 }, "maxResponseTime": { "total": 24, "ok": 24, "ko": 0 }, "meanResponseTime": { "total": 24, "ok": 24, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 24, "ok": 24, "ko": 0 }, "percentiles2": { "total": 24, "ok": 24, "ko": 0 }, "percentiles3": { "total": 24, "ok": 24, "ko": 0 }, "percentiles4": { "total": 24, "ok": 24, "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": 12, "ok": 12, "ko": 0 }, "maxResponseTime": { "total": 12, "ok": 12, "ko": 0 }, "meanResponseTime": { "total": 12, "ok": 12, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 12, "ok": 12, "ko": 0 }, "percentiles2": { "total": 12, "ok": 12, "ko": 0 }, "percentiles3": { "total": 12, "ok": 12, "ko": 0 }, "percentiles4": { "total": 12, "ok": 12, "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": 21, "ok": 21, "ko": 0 }, "maxResponseTime": { "total": 21, "ok": 21, "ko": 0 }, "meanResponseTime": { "total": 21, "ok": 21, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 21, "ok": 21, "ko": 0 }, "percentiles2": { "total": 21, "ok": 21, "ko": 0 }, "percentiles3": { "total": 21, "ok": 21, "ko": 0 }, "percentiles4": { "total": 21, "ok": 21, "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": "ReadManagedUser", "numberOfRequests": { "total": 18231, "ok": 18231, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 70, "ok": 70, "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": 8, "ok": 8, "ko": 0 }, "percentiles4": { "total": 11, "ok": 11, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 18231, "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": 9.956854178044784, "ok": 9.956854178044784, "ko": 0 }, "spikes": [] }, { "name": "queryBySn", "numberOfRequests": { "total": 17926, "ok": 17926, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 65, "ok": 65, "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": 13, "ok": 13, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 17926, "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": 9.79027853631895, "ok": 9.79027853631895, "ko": 0 }, "spikes": [] }, { "name": "queryFull", "numberOfRequests": { "total": 17944, "ok": 17944, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 66, "ok": 66, "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": 9, "ok": 9, "ko": 0 }, "percentiles4": { "total": 13, "ok": 13, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 17944, "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": 9.800109229929001, "ok": 9.800109229929001, "ko": 0 }, "spikes": [] }, { "name": "queryStartsWith", "numberOfRequests": { "total": 18381, "ok": 18381, "ko": 0 }, "minResponseTime": { "total": 84, "ok": 84, "ko": 0 }, "maxResponseTime": { "total": 538, "ok": 538, "ko": 0 }, "meanResponseTime": { "total": 236, "ok": 236, "ko": 0 }, "standardDeviation": { "total": 33, "ok": 33, "ko": 0 }, "percentiles1": { "total": 239, "ok": 239, "ko": 0 }, "percentiles2": { "total": 255, "ok": 255, "ko": 0 }, "percentiles3": { "total": 281, "ok": 281, "ko": 0 }, "percentiles4": { "total": 302, "ok": 302, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 18381, "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": 10.038776624795194, "ok": 10.038776624795194, "ko": 0 }, "spikes": [] }, { "name": "queryPartial", "numberOfRequests": { "total": 18158, "ok": 18158, "ko": 0 }, "minResponseTime": { "total": 162, "ok": 162, "ko": 0 }, "maxResponseTime": { "total": 393, "ok": 393, "ko": 0 }, "meanResponseTime": { "total": 236, "ok": 236, "ko": 0 }, "standardDeviation": { "total": 29, "ok": 29, "ko": 0 }, "percentiles1": { "total": 232, "ok": 232, "ko": 0 }, "percentiles2": { "total": 253, "ok": 253, "ko": 0 }, "percentiles3": { "total": 288, "ok": 288, "ko": 0 }, "percentiles4": { "total": 314, "ok": 314, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 18158, "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": 9.916985253959584, "ok": 9.916985253959584, "ko": 0 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 50, "avg_response_time": 99, "max_response_time": 538, "min_response_time": 5, "num_requests": 90643, "num_requests_pass": 90643, "num_requests_percent_pass": 100.0 }, "options": "; duration:1800; simulation:com.forgerock.gatling.Loadtest; testCase:idm.MixedQuery; testMode:open; throughput:50", "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": 24, "total": 24 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 24, "total": 24 }, "minResponseTime": { "ko": 0, "ok": 24, "total": 24 }, "name": "AdminRestLogin", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 24, "total": 24 }, "percentiles2": { "ko": 0, "ok": 24, "total": 24 }, "percentiles3": { "ko": 0, "ok": 24, "total": 24 }, "percentiles4": { "ko": 0, "ok": 24, "total": 24 }, "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": 12, "total": 12 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 12, "total": 12 }, "minResponseTime": { "ko": 0, "ok": 12, "total": 12 }, "name": "AdminAuthorize", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 12, "total": 12 }, "percentiles2": { "ko": 0, "ok": 12, "total": 12 }, "percentiles3": { "ko": 0, "ok": 12, "total": 12 }, "percentiles4": { "ko": 0, "ok": 12, "total": 12 }, "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": 21, "total": 21 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 21, "total": 21 }, "minResponseTime": { "ko": 0, "ok": 21, "total": 21 }, "name": "Admin AccessToken stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 21, "total": 21 }, "percentiles2": { "ko": 0, "ok": 21, "total": 21 }, "percentiles3": { "ko": 0, "ok": 21, "total": 21 }, "percentiles4": { "ko": 0, "ok": 21, "total": 21 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 18231, "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": 70, "total": 70 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 9.956854178044784, "total": 9.956854178044784 }, "meanResponseTime": { "ko": 0, "ok": 6, "total": 6 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "ReadManagedUser", "numberOfRequests": { "ko": 0, "ok": 18231, "total": 18231 }, "percentiles1": { "ko": 0, "ok": 6, "total": 6 }, "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 } }, { "group1": { "count": 17926, "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": 65, "total": 65 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 9.79027853631895, "total": 9.79027853631895 }, "meanResponseTime": { "ko": 0, "ok": 7, "total": 7 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryBySn", "numberOfRequests": { "ko": 0, "ok": 17926, "total": 17926 }, "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": 13, "total": 13 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } }, { "group1": { "count": 17944, "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": 66, "total": 66 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 9.800109229929001, "total": 9.800109229929001 }, "meanResponseTime": { "ko": 0, "ok": 7, "total": 7 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryFull", "numberOfRequests": { "ko": 0, "ok": 17944, "total": 17944 }, "percentiles1": { "ko": 0, "ok": 7, "total": 7 }, "percentiles2": { "ko": 0, "ok": 7, "total": 7 }, "percentiles3": { "ko": 0, "ok": 9, "total": 9 }, "percentiles4": { "ko": 0, "ok": 13, "total": 13 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } }, { "group1": { "count": 18381, "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": 538, "total": 538 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 10.038776624795194, "total": 10.038776624795194 }, "meanResponseTime": { "ko": 0, "ok": 236, "total": 236 }, "minResponseTime": { "ko": 0, "ok": 84, "total": 84 }, "name": "queryStartsWith", "numberOfRequests": { "ko": 0, "ok": 18381, "total": 18381 }, "percentiles1": { "ko": 0, "ok": 239, "total": 239 }, "percentiles2": { "ko": 0, "ok": 255, "total": 255 }, "percentiles3": { "ko": 0, "ok": 281, "total": 281 }, "percentiles4": { "ko": 0, "ok": 302, "total": 302 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 33, "total": 33 } }, { "group1": { "count": 18158, "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": 393, "total": 393 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 9.916985253959584, "total": 9.916985253959584 }, "meanResponseTime": { "ko": 0, "ok": 236, "total": 236 }, "minResponseTime": { "ko": 0, "ok": 162, "total": 162 }, "name": "queryPartial", "numberOfRequests": { "ko": 0, "ok": 18158, "total": 18158 }, "percentiles1": { "ko": 0, "ok": 232, "total": 232 }, "percentiles2": { "ko": 0, "ok": 253, "total": 253 }, "percentiles3": { "ko": 0, "ok": 288, "total": 288 }, "percentiles4": { "ko": 0, "ok": 314, "total": 314 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 29, "total": 29 } } ], "global": { "group1": { "count": 90643, "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": 538, "total": 538 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 49.504642271982526, "total": 49.504642271982526 }, "meanResponseTime": { "ko": 0, "ok": 99, "total": 99 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "All Requests", "numberOfRequests": { "ko": 0, "ok": 90643, "total": 90643 }, "percentiles1": { "ko": 0, "ok": 8, "total": 8 }, "percentiles2": { "ko": 0, "ok": 228, "total": 228 }, "percentiles3": { "ko": 0, "ok": 268, "total": 268 }, "percentiles4": { "ko": 0, "ok": 295, "total": 295 }, "standardDeviation": { "ko": 0, "ok": 114, "total": 114 } } }, "target_hostname": "xlou.iam.xlou-cdm.engineeringpit.com", "tool_name": "gatling" } ________________________________________________________________________________ [2024-04-24 09:57:27] Reporting-mixedquery-50 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2024-04-24 09:57:27] Reporting-mixedquery-50 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/05_mixedquery-50/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2024-04-24 09:57:27] Reporting-mixedquery-50 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped