--Task-- name: Reporting-mixedquery-10 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: [] preceding_task: None options: {'task_name': 'mixedquery-10'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 09:54:50] Reporting-mixedquery-10 pre : N/A ________________________________________________________________________________ source (overseer-0) is a component [2024-04-24 09:54:50] - INFO: Timeout for this Task has changed to 2h (7200 seconds) [2024-04-24 09:54:50] - 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-10/stdout.txt Pod stderr file : /results/orders/Reporting-mixedquery-10/stderr.txt Pod log : /results/orders/Reporting-mixedquery-10 ________________________________________________________________________________ [2024-04-24 09:54:50] Reporting-mixedquery-10 step1 : Write order for overseer to generate the report ________________________________________________________________________________ 2024-04-24 09:54:50,283 INFO 2024-04-24 09:54:50,283 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:54:50,283 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:51,780 INFO [loop_until]: Function succeeded after 1s (rc=0) - expected number of elements found 2024-04-24 09:54:51,780 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:51,780 DEBUG --- stdout --- 2024-04-24 09:54:51,780 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:51,780 DEBUG --- stderr --- 2024-04-24 09:54:51,780 DEBUG [2024-04-24 09:54:51] - INFO: Get overseer pod resources requests memory 2024-04-24 09:54:51,781 INFO 2024-04-24 09:54:51,782 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-55d5975bd7-gp92m --output jsonpath={.spec.containers[].resources.requests.memory} 2024-04-24 09:54:51,782 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:54:52,816 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:52,817 DEBUG --- stdout --- 2024-04-24 09:54:52,817 DEBUG 4Gi 2024-04-24 09:54:52,817 DEBUG --- stderr --- 2024-04-24 09:54:52,817 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/09_Reporting-mixedquery-10/order.json { "name": "Reporting-mixedquery-10", "command": "/lodestar/clients/gatling-report-only-for-pyrock.sh mixedquery-10 --extra-run-jvm-options \"-Xmx12G\"", "out-dir": "/results/orders/Reporting-mixedquery-10" } ________________________________________________________________________________ [2024-04-24 09:54:52] Reporting-mixedquery-10 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2024-04-24 09:54:52,821 INFO 2024-04-24 09:54:52,821 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:54:52,821 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2024-04-24 09:54:53,479 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:54:53,479 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:53,479 DEBUG --- stdout --- 2024-04-24 09:54:53,479 DEBUG WAITING 2024-04-24 09:54:53,479 DEBUG --- stderr --- 2024-04-24 09:54:53,479 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 18 0 --:--:-- --:--:-- --:--:-- 18 ________________________________________________________________________________ [2024-04-24 09:54:53] Reporting-mixedquery-10 step3 : Send order to overseer ________________________________________________________________________________ 2024-04-24 09:54:53,483 INFO 2024-04-24 09:54:53,483 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:54:53,483 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:54,407 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:54,408 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:54,408 DEBUG --- stdout --- 2024-04-24 09:54:54,408 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:54,408 DEBUG --- stderr --- 2024-04-24 09:54:54,408 DEBUG 2024-04-24 09:54:54,409 INFO 2024-04-24 09:54:54,409 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/09_Reporting-mixedquery-10/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 09:54:54,409 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:54:56,230 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:56,231 DEBUG --- stdout --- 2024-04-24 09:54:56,231 DEBUG 2024-04-24 09:54:56,231 DEBUG --- stderr --- 2024-04-24 09:54:56,231 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2024-04-24 09:54:56] Reporting-mixedquery-10 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2024-04-24 09:55:01,235 INFO 2024-04-24 09:55:01,235 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:55:01,235 INFO [loop_until]: (max_time=7200, interval=5, expected_rc=[0] 2024-04-24 09:55:01,531 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2024-04-24 09:55:06,779 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2024-04-24 09:55:06,779 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:06,779 DEBUG --- stdout --- 2024-04-24 09:55:06,779 DEBUG WAITING 2024-04-24 09:55:06,779 DEBUG --- stderr --- 2024-04-24 09:55:06,779 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-24 09:55:06] Reporting-mixedquery-10 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2024-04-24 09:55:06,781 INFO 2024-04-24 09:55:06,781 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:06,781 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:07,106 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:07,106 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:07,106 DEBUG --- stdout --- 2024-04-24 09:55:07,106 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:07,107 DEBUG --- stderr --- 2024-04-24 09:55:07,107 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:55:07,107 INFO 2024-04-24 09:55:07,107 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:07,107 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:07,456 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:07,457 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:07,457 DEBUG --- stdout --- 2024-04-24 09:55:07,457 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:07,457 DEBUG --- stderr --- 2024-04-24 09:55:07,457 DEBUG 2024-04-24 09:55:07,458 INFO 2024-04-24 09:55:07,458 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-10.tar.gz" ]; then echo "/results/gatling/mixedquery-10.tar.gz file found"; exit 0; else echo "/results/gatling/mixedquery-10.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/mixedquery-10.tar.gz file found" 2024-04-24 09:55:07,458 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:55:08,358 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:55:08,359 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:08,359 DEBUG --- stdout --- 2024-04-24 09:55:08,359 DEBUG /results/gatling/mixedquery-10.tar.gz file found 2024-04-24 09:55:08,359 DEBUG --- stderr --- 2024-04-24 09:55:08,359 DEBUG 2024-04-24 09:55:08,360 INFO 2024-04-24 09:55:08,360 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:08,360 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:08,693 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:08,693 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:08,693 DEBUG --- stdout --- 2024-04-24 09:55:08,694 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:08,694 DEBUG --- stderr --- 2024-04-24 09:55:08,694 DEBUG 2024-04-24 09:55:08,694 INFO 2024-04-24 09:55:08,694 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-10.tar.gz 2024-04-24 09:55:09,556 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:55:09,556 DEBUG --- stdout --- 2024-04-24 09:55:09,556 DEBUG 336 /results/gatling/mixedquery-10.tar.gz 2024-04-24 09:55:09,557 DEBUG --- stderr --- 2024-04-24 09:55:09,557 DEBUG 2024-04-24 09:55:09,557 INFO - Download file (size 336k) 2024-04-24 09:55:09,557 INFO 2024-04-24 09:55:09,557 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:09,557 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:09,904 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:09,904 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:09,904 DEBUG --- stdout --- 2024-04-24 09:55:09,904 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:09,904 DEBUG --- stderr --- 2024-04-24 09:55:09,904 DEBUG source : /results/gatling/mixedquery-10.tar.gz @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-10.tar.gz 2024-04-24 09:55:09,905 INFO 2024-04-24 09:55:09,905 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:55:09,905 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:10,273 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:10,274 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:10,274 DEBUG --- stdout --- 2024-04-24 09:55:10,274 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:10,274 DEBUG --- stderr --- 2024-04-24 09:55:10,274 DEBUG 2024-04-24 09:55:10,275 INFO 2024-04-24 09:55:10,275 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/gatling/mixedquery-10.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-10.tar.gz 2024-04-24 09:55:10,275 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:55:11,325 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:11,325 DEBUG --- stdout --- 2024-04-24 09:55:11,326 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:55:11,326 DEBUG --- stderr --- 2024-04-24 09:55:11,326 DEBUG ________________________________________________________________________________ [2024-04-24 09:55:11] Reporting-mixedquery-10 step6 : Unzip the result ________________________________________________________________________________ 2024-04-24 09:55:11,327 INFO 2024-04-24 09:55:11,327 INFO [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-10.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/gatling 2024-04-24 09:55:11,376 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:55:11,376 DEBUG --- stdout --- 2024-04-24 09:55:11,376 DEBUG 2024-04-24 09:55:11,376 DEBUG --- stderr --- 2024-04-24 09:55:11,376 DEBUG 2024-04-24 09:55:11,376 INFO ________________________________________________________________________________ [2024-04-24 09:55:11] Reporting-mixedquery-10 step7 : Print rc order ________________________________________________________________________________ 2024-04-24 09:55:11,377 INFO 2024-04-24 09:55:11,377 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:11,377 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:11,737 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:11,737 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:11,737 DEBUG --- stdout --- 2024-04-24 09:55:11,737 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:11,737 DEBUG --- stderr --- 2024-04-24 09:55:11,738 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:55:11,738 INFO 2024-04-24 09:55:11,738 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:11,738 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:12,087 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:12,087 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:12,087 DEBUG --- stdout --- 2024-04-24 09:55:12,087 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:12,087 DEBUG --- stderr --- 2024-04-24 09:55:12,087 DEBUG 2024-04-24 09:55:12,088 INFO 2024-04-24 09:55:12,088 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-10/rc.txt" ]; then echo "/results/orders/Reporting-mixedquery-10/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-10/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-10/rc.txt file found" 2024-04-24 09:55:12,088 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:55:12,952 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:55:12,952 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:12,952 DEBUG --- stdout --- 2024-04-24 09:55:12,952 DEBUG /results/orders/Reporting-mixedquery-10/rc.txt file found 2024-04-24 09:55:12,952 DEBUG --- stderr --- 2024-04-24 09:55:12,952 DEBUG 2024-04-24 09:55:12,953 INFO 2024-04-24 09:55:12,953 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:12,953 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:13,334 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:13,334 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:13,334 DEBUG --- stdout --- 2024-04-24 09:55:13,334 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:13,334 DEBUG --- stderr --- 2024-04-24 09:55:13,334 DEBUG 2024-04-24 09:55:13,334 INFO 2024-04-24 09:55:13,334 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-10/rc.txt 2024-04-24 09:55:14,183 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:55:14,183 DEBUG --- stdout --- 2024-04-24 09:55:14,183 DEBUG 4 /results/orders/Reporting-mixedquery-10/rc.txt 2024-04-24 09:55:14,183 DEBUG --- stderr --- 2024-04-24 09:55:14,183 DEBUG 2024-04-24 09:55:14,183 INFO - Download file (size 4k) 2024-04-24 09:55:14,183 INFO 2024-04-24 09:55:14,184 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:14,184 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:14,509 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:14,509 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:14,509 DEBUG --- stdout --- 2024-04-24 09:55:14,509 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:14,509 DEBUG --- stderr --- 2024-04-24 09:55:14,509 DEBUG source : /results/orders/Reporting-mixedquery-10/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/09_Reporting-mixedquery-10/rc.txt 2024-04-24 09:55:14,510 INFO 2024-04-24 09:55:14,510 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:14,510 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:14,853 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:14,853 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:14,853 DEBUG --- stdout --- 2024-04-24 09:55:14,853 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:14,853 DEBUG --- stderr --- 2024-04-24 09:55:14,853 DEBUG 2024-04-24 09:55:14,854 INFO 2024-04-24 09:55:14,854 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-10/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/09_Reporting-mixedquery-10/rc.txt 2024-04-24 09:55:14,854 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:55:15,629 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:15,629 DEBUG --- stdout --- 2024-04-24 09:55:15,630 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:55:15,630 DEBUG --- stderr --- 2024-04-24 09:55:15,631 DEBUG 2024-04-24 09:55:15,632 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/09_Reporting-mixedquery-10/rc.txt is small (1 lines), printing all lines: 2024-04-24 09:55:15,632 DEBUG ----- output ----- 2024-04-24 09:55:15,632 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 09:55:15,632 INFO ________________________________________________________________________________ [2024-04-24 09:55:15] Reporting-mixedquery-10 step8 : Check rc log ________________________________________________________________________________ PASS: no error found in rc log ________________________________________________________________________________ [2024-04-24 09:55:15] Reporting-mixedquery-10 step9 : Print stdout order ________________________________________________________________________________ 2024-04-24 09:55:15,636 INFO 2024-04-24 09:55:15,636 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:15,636 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:15,985 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:15,985 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:15,986 DEBUG --- stdout --- 2024-04-24 09:55:15,986 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:15,986 DEBUG --- stderr --- 2024-04-24 09:55:15,986 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:55:15,986 INFO 2024-04-24 09:55:15,986 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:15,986 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:16,339 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:16,339 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:16,339 DEBUG --- stdout --- 2024-04-24 09:55:16,339 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:16,339 DEBUG --- stderr --- 2024-04-24 09:55:16,339 DEBUG 2024-04-24 09:55:16,340 INFO 2024-04-24 09:55:16,340 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-10/stdout.txt" ]; then echo "/results/orders/Reporting-mixedquery-10/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-10/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-10/stdout.txt file found" 2024-04-24 09:55:16,340 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:55:17,252 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:55:17,253 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:17,253 DEBUG --- stdout --- 2024-04-24 09:55:17,253 DEBUG /results/orders/Reporting-mixedquery-10/stdout.txt file found 2024-04-24 09:55:17,253 DEBUG --- stderr --- 2024-04-24 09:55:17,253 DEBUG 2024-04-24 09:55:17,254 INFO 2024-04-24 09:55:17,254 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:17,254 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:17,628 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:17,628 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:17,628 DEBUG --- stdout --- 2024-04-24 09:55:17,628 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:17,629 DEBUG --- stderr --- 2024-04-24 09:55:17,629 DEBUG 2024-04-24 09:55:17,629 INFO 2024-04-24 09:55:17,629 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-10/stdout.txt 2024-04-24 09:55:18,528 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:55:18,529 DEBUG --- stdout --- 2024-04-24 09:55:18,529 DEBUG 4 /results/orders/Reporting-mixedquery-10/stdout.txt 2024-04-24 09:55:18,529 DEBUG --- stderr --- 2024-04-24 09:55:18,529 DEBUG 2024-04-24 09:55:18,529 INFO - Download file (size 4k) 2024-04-24 09:55:18,529 INFO 2024-04-24 09:55:18,529 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:18,530 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:18,948 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:18,948 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:18,948 DEBUG --- stdout --- 2024-04-24 09:55:18,949 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:18,949 DEBUG --- stderr --- 2024-04-24 09:55:18,949 DEBUG source : /results/orders/Reporting-mixedquery-10/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/09_Reporting-mixedquery-10/stdout.txt 2024-04-24 09:55:18,949 INFO 2024-04-24 09:55:18,949 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:18,949 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:19,361 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:19,361 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:19,361 DEBUG --- stdout --- 2024-04-24 09:55:19,361 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:19,362 DEBUG --- stderr --- 2024-04-24 09:55:19,362 DEBUG 2024-04-24 09:55:19,362 INFO 2024-04-24 09:55:19,362 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-10/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/09_Reporting-mixedquery-10/stdout.txt 2024-04-24 09:55:19,362 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:55:20,177 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:20,177 DEBUG --- stdout --- 2024-04-24 09:55:20,177 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:55:20,177 DEBUG --- stderr --- 2024-04-24 09:55:20,178 DEBUG 2024-04-24 09:55:20,178 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/09_Reporting-mixedquery-10/stdout.txt is small (81 lines), printing all lines: 2024-04-24 09:55:20,178 DEBUG ----- output ----- 2024-04-24 09:55:20,178 DEBUG [cmd] /lodestar/clients/gatling/bin/gatling.sh --reports-only /results/gatling/mixedquery-10/loadtest-20240424053334179 --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 17987 (OK=17987 KO=0 ) > min response time 5 (OK=5 KO=- ) > max response time 925 (OK=925 KO=- ) > mean response time 133 (OK=133 KO=- ) > std deviation 166 (OK=166 KO=- ) > response time 50th percentile 9 (OK=9 KO=- ) > response time 75th percentile 287 (OK=287 KO=- ) > response time 95th percentile 428 (OK=428 KO=- ) > response time 99th percentile 554 (OK=553 KO=- ) > mean requests/sec 9.829 (OK=9.829 KO=- ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 17985 (100%) > 800 ms <= t < 1200 ms 2 ( 0%) > t >= 1200 ms 0 ( 0%) > failed 0 ( 0%) ================================================================================ Reports generated in 0s. Please open the following file: file:///results/gatling/mixedquery-10/loadtest-20240424053334179/index.html ---------------------- [24/Apr/2024 10:55:02] Create tar file /results/gatling/mixedquery-10.tar.gz ---------------------- SIMULATION_PATH=/results/gatling/mixedquery-10/loadtest-20240424053334179 RESULT_FOLDER=mixedquery-10 RESULT_PATH=/results/gatling/mixedquery-10 PWD=/results/gatling/mixedquery-10/loadtest-20240424053334179 [cmd] tar -czvf /results/gatling/mixedquery-10.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:55:20,178 INFO ________________________________________________________________________________ [2024-04-24 09:55:20] Reporting-mixedquery-10 step10 : Check stdout log ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2024-04-24 09:55:20] Reporting-mixedquery-10 step11 : Print stderr order ________________________________________________________________________________ 2024-04-24 09:55:20,180 INFO 2024-04-24 09:55:20,180 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:20,180 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:20,515 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:20,516 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:20,516 DEBUG --- stdout --- 2024-04-24 09:55:20,516 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:20,516 DEBUG --- stderr --- 2024-04-24 09:55:20,516 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:55:20,516 INFO 2024-04-24 09:55:20,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:55:20,516 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:20,870 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:20,870 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:20,870 DEBUG --- stdout --- 2024-04-24 09:55:20,870 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:20,870 DEBUG --- stderr --- 2024-04-24 09:55:20,870 DEBUG 2024-04-24 09:55:20,871 INFO 2024-04-24 09:55:20,871 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-10/stderr.txt" ]; then echo "/results/orders/Reporting-mixedquery-10/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-10/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-10/stderr.txt file found" 2024-04-24 09:55:20,871 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:55:21,723 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:55:21,724 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:21,724 DEBUG --- stdout --- 2024-04-24 09:55:21,724 DEBUG /results/orders/Reporting-mixedquery-10/stderr.txt file found 2024-04-24 09:55:21,724 DEBUG --- stderr --- 2024-04-24 09:55:21,724 DEBUG 2024-04-24 09:55:21,724 INFO 2024-04-24 09:55:21,724 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:21,724 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:22,044 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:22,045 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:22,045 DEBUG --- stdout --- 2024-04-24 09:55:22,045 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:22,045 DEBUG --- stderr --- 2024-04-24 09:55:22,045 DEBUG 2024-04-24 09:55:22,045 INFO 2024-04-24 09:55:22,045 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-10/stderr.txt 2024-04-24 09:55:22,913 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:55:22,913 DEBUG --- stdout --- 2024-04-24 09:55:22,913 DEBUG 0 /results/orders/Reporting-mixedquery-10/stderr.txt 2024-04-24 09:55:22,914 DEBUG --- stderr --- 2024-04-24 09:55:22,914 DEBUG 2024-04-24 09:55:22,914 INFO - Download file (size 0k) 2024-04-24 09:55:22,914 INFO 2024-04-24 09:55:22,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:55:22,914 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:23,272 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:23,273 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:23,273 DEBUG --- stdout --- 2024-04-24 09:55:23,273 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:23,273 DEBUG --- stderr --- 2024-04-24 09:55:23,273 DEBUG source : /results/orders/Reporting-mixedquery-10/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/09_Reporting-mixedquery-10/stderr.txt 2024-04-24 09:55:23,274 INFO 2024-04-24 09:55:23,274 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:23,274 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:23,641 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:23,641 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:23,641 DEBUG --- stdout --- 2024-04-24 09:55:23,641 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:23,641 DEBUG --- stderr --- 2024-04-24 09:55:23,641 DEBUG 2024-04-24 09:55:23,642 INFO 2024-04-24 09:55:23,642 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-10/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/09_Reporting-mixedquery-10/stderr.txt 2024-04-24 09:55:23,642 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:55:24,535 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:24,536 DEBUG --- stdout --- 2024-04-24 09:55:24,536 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:55:24,536 DEBUG --- stderr --- 2024-04-24 09:55:24,536 DEBUG 2024-04-24 09:55:24,538 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/09_Reporting-mixedquery-10/stderr.txt is small (0 lines), printing all lines: 2024-04-24 09:55:24,538 DEBUG ----- output ----- 2024-04-24 09:55:24,538 DEBUG 2024-04-24 09:55:24,538 INFO ________________________________________________________________________________ [2024-04-24 09:55:24] Reporting-mixedquery-10 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-24 09:55:24] Reporting-mixedquery-10 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 queryFull checking simulation request queryStartsWith checking simulation request queryBySn checking simulation request queryPartial no spikes found ________________________________________________________________________________ [2024-04-24 09:55:24] Reporting-mixedquery-10 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 17987, "ok": 17987, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 925, "ok": 925, "ko": 0 }, "meanResponseTime": { "total": 133, "ok": 133, "ko": 0 }, "standardDeviation": { "total": 166, "ok": 166, "ko": 0 }, "percentiles1": { "total": 9, "ok": 9, "ko": 0 }, "percentiles2": { "total": 287, "ok": 287, "ko": 0 }, "percentiles3": { "total": 428, "ok": 428, "ko": 0 }, "percentiles4": { "total": 554, "ok": 553, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 17985, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 2, "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.82896174863388, "ok": 9.82896174863388, "ko": 0 } }, "details": [ { "name": "AdminRestLogin", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 86, "ok": 86, "ko": 0 }, "maxResponseTime": { "total": 86, "ok": 86, "ko": 0 }, "meanResponseTime": { "total": 86, "ok": 86, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 86, "ok": 86, "ko": 0 }, "percentiles2": { "total": 86, "ok": 86, "ko": 0 }, "percentiles3": { "total": 86, "ok": 86, "ko": 0 }, "percentiles4": { "total": 86, "ok": 86, "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": 36, "ok": 36, "ko": 0 }, "maxResponseTime": { "total": 36, "ok": 36, "ko": 0 }, "meanResponseTime": { "total": 36, "ok": 36, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 36, "ok": 36, "ko": 0 }, "percentiles2": { "total": 36, "ok": 36, "ko": 0 }, "percentiles3": { "total": 36, "ok": 36, "ko": 0 }, "percentiles4": { "total": 36, "ok": 36, "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": 29, "ok": 29, "ko": 0 }, "maxResponseTime": { "total": 29, "ok": 29, "ko": 0 }, "meanResponseTime": { "total": 29, "ok": 29, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 29, "ok": 29, "ko": 0 }, "percentiles2": { "total": 29, "ok": 29, "ko": 0 }, "percentiles3": { "total": 29, "ok": 29, "ko": 0 }, "percentiles4": { "total": 29, "ok": 29, "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": 3648, "ok": 3648, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 77, "ok": 77, "ko": 0 }, "meanResponseTime": { "total": 8, "ok": 8, "ko": 0 }, "standardDeviation": { "total": 4, "ok": 4, "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": 3648, "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": 1.9934426229508198, "ok": 1.9934426229508198, "ko": 0 }, "spikes": [] }, { "name": "queryFull", "numberOfRequests": { "total": 3640, "ok": 3640, "ko": 0 }, "minResponseTime": { "total": 6, "ok": 6, "ko": 0 }, "maxResponseTime": { "total": 98, "ok": 98, "ko": 0 }, "meanResponseTime": { "total": 8, "ok": 8, "ko": 0 }, "standardDeviation": { "total": 4, "ok": 4, "ko": 0 }, "percentiles1": { "total": 8, "ok": 8, "ko": 0 }, "percentiles2": { "total": 8, "ok": 8, "ko": 0 }, "percentiles3": { "total": 10, "ok": 10, "ko": 0 }, "percentiles4": { "total": 14, "ok": 14, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 3640, "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": 1.989071038251366, "ok": 1.989071038251366, "ko": 0 }, "spikes": [] }, { "name": "queryStartsWith", "numberOfRequests": { "total": 3539, "ok": 3539, "ko": 0 }, "minResponseTime": { "total": 83, "ok": 83, "ko": 0 }, "maxResponseTime": { "total": 581, "ok": 581, "ko": 0 }, "meanResponseTime": { "total": 268, "ok": 268, "ko": 0 }, "standardDeviation": { "total": 64, "ok": 64, "ko": 0 }, "percentiles1": { "total": 280, "ok": 280, "ko": 0 }, "percentiles2": { "total": 300, "ok": 300, "ko": 0 }, "percentiles3": { "total": 347, "ok": 347, "ko": 0 }, "percentiles4": { "total": 394, "ok": 394, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 3539, "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": 1.933879781420765, "ok": 1.933879781420765, "ko": 0 }, "spikes": [] }, { "name": "queryBySn", "numberOfRequests": { "total": 3529, "ok": 3529, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 76, "ok": 76, "ko": 0 }, "meanResponseTime": { "total": 8, "ok": 8, "ko": 0 }, "standardDeviation": { "total": 3, "ok": 3, "ko": 0 }, "percentiles1": { "total": 8, "ok": 8, "ko": 0 }, "percentiles2": { "total": 8, "ok": 8, "ko": 0 }, "percentiles3": { "total": 10, "ok": 10, "ko": 0 }, "percentiles4": { "total": 13, "ok": 13, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 3529, "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": 1.9284153005464482, "ok": 1.9284153005464482, "ko": 0 }, "spikes": [] }, { "name": "queryPartial", "numberOfRequests": { "total": 3628, "ok": 3628, "ko": 0 }, "minResponseTime": { "total": 162, "ok": 162, "ko": 0 }, "maxResponseTime": { "total": 925, "ok": 925, "ko": 0 }, "meanResponseTime": { "total": 374, "ok": 374, "ko": 0 }, "standardDeviation": { "total": 101, "ok": 101, "ko": 0 }, "percentiles1": { "total": 373, "ok": 373, "ko": 0 }, "percentiles2": { "total": 426, "ok": 426, "ko": 0 }, "percentiles3": { "total": 553, "ok": 553, "ko": 0 }, "percentiles4": { "total": 621, "ok": 621, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 3626, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 2, "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": 1.9825136612021859, "ok": 1.9825136612021859, "ko": 0 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 10, "avg_response_time": 133, "max_response_time": 925, "min_response_time": 5, "num_requests": 17987, "num_requests_pass": 17987, "num_requests_percent_pass": 100.0 }, "options": "; duration:1800; simulation:com.forgerock.gatling.Loadtest; testCase:idm.MixedQuery; testMode:open; throughput:10", "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": 86, "total": 86 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.000546448087431694, "total": 0.000546448087431694 }, "meanResponseTime": { "ko": 0, "ok": 86, "total": 86 }, "minResponseTime": { "ko": 0, "ok": 86, "total": 86 }, "name": "AdminRestLogin", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 86, "total": 86 }, "percentiles2": { "ko": 0, "ok": 86, "total": 86 }, "percentiles3": { "ko": 0, "ok": 86, "total": 86 }, "percentiles4": { "ko": 0, "ok": 86, "total": 86 }, "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": 36, "total": 36 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.000546448087431694, "total": 0.000546448087431694 }, "meanResponseTime": { "ko": 0, "ok": 36, "total": 36 }, "minResponseTime": { "ko": 0, "ok": 36, "total": 36 }, "name": "AdminAuthorize", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 36, "total": 36 }, "percentiles2": { "ko": 0, "ok": 36, "total": 36 }, "percentiles3": { "ko": 0, "ok": 36, "total": 36 }, "percentiles4": { "ko": 0, "ok": 36, "total": 36 }, "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": 29, "total": 29 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.000546448087431694, "total": 0.000546448087431694 }, "meanResponseTime": { "ko": 0, "ok": 29, "total": 29 }, "minResponseTime": { "ko": 0, "ok": 29, "total": 29 }, "name": "Admin AccessToken stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 29, "total": 29 }, "percentiles2": { "ko": 0, "ok": 29, "total": 29 }, "percentiles3": { "ko": 0, "ok": 29, "total": 29 }, "percentiles4": { "ko": 0, "ok": 29, "total": 29 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 3648, "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": 77, "total": 77 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 1.9934426229508198, "total": 1.9934426229508198 }, "meanResponseTime": { "ko": 0, "ok": 8, "total": 8 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "ReadManagedUser", "numberOfRequests": { "ko": 0, "ok": 3648, "total": 3648 }, "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": 4, "total": 4 } }, { "group1": { "count": 3640, "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": 98, "total": 98 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 1.989071038251366, "total": 1.989071038251366 }, "meanResponseTime": { "ko": 0, "ok": 8, "total": 8 }, "minResponseTime": { "ko": 0, "ok": 6, "total": 6 }, "name": "queryFull", "numberOfRequests": { "ko": 0, "ok": 3640, "total": 3640 }, "percentiles1": { "ko": 0, "ok": 8, "total": 8 }, "percentiles2": { "ko": 0, "ok": 8, "total": 8 }, "percentiles3": { "ko": 0, "ok": 10, "total": 10 }, "percentiles4": { "ko": 0, "ok": 14, "total": 14 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 4, "total": 4 } }, { "group1": { "count": 3539, "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": 581, "total": 581 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 1.933879781420765, "total": 1.933879781420765 }, "meanResponseTime": { "ko": 0, "ok": 268, "total": 268 }, "minResponseTime": { "ko": 0, "ok": 83, "total": 83 }, "name": "queryStartsWith", "numberOfRequests": { "ko": 0, "ok": 3539, "total": 3539 }, "percentiles1": { "ko": 0, "ok": 280, "total": 280 }, "percentiles2": { "ko": 0, "ok": 300, "total": 300 }, "percentiles3": { "ko": 0, "ok": 347, "total": 347 }, "percentiles4": { "ko": 0, "ok": 394, "total": 394 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 64, "total": 64 } }, { "group1": { "count": 3529, "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": 76, "total": 76 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 1.9284153005464482, "total": 1.9284153005464482 }, "meanResponseTime": { "ko": 0, "ok": 8, "total": 8 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryBySn", "numberOfRequests": { "ko": 0, "ok": 3529, "total": 3529 }, "percentiles1": { "ko": 0, "ok": 8, "total": 8 }, "percentiles2": { "ko": 0, "ok": 8, "total": 8 }, "percentiles3": { "ko": 0, "ok": 10, "total": 10 }, "percentiles4": { "ko": 0, "ok": 13, "total": 13 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 3, "total": 3 } }, { "group1": { "count": 3626, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 2, "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": 925, "total": 925 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 1.9825136612021859, "total": 1.9825136612021859 }, "meanResponseTime": { "ko": 0, "ok": 374, "total": 374 }, "minResponseTime": { "ko": 0, "ok": 162, "total": 162 }, "name": "queryPartial", "numberOfRequests": { "ko": 0, "ok": 3628, "total": 3628 }, "percentiles1": { "ko": 0, "ok": 373, "total": 373 }, "percentiles2": { "ko": 0, "ok": 426, "total": 426 }, "percentiles3": { "ko": 0, "ok": 553, "total": 553 }, "percentiles4": { "ko": 0, "ok": 621, "total": 621 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 101, "total": 101 } } ], "global": { "group1": { "count": 17985, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 2, "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": 925, "total": 925 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 9.82896174863388, "total": 9.82896174863388 }, "meanResponseTime": { "ko": 0, "ok": 133, "total": 133 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "All Requests", "numberOfRequests": { "ko": 0, "ok": 17987, "total": 17987 }, "percentiles1": { "ko": 0, "ok": 9, "total": 9 }, "percentiles2": { "ko": 0, "ok": 287, "total": 287 }, "percentiles3": { "ko": 0, "ok": 428, "total": 428 }, "percentiles4": { "ko": 0, "ok": 553, "total": 554 }, "standardDeviation": { "ko": 0, "ok": 166, "total": 166 } } }, "target_hostname": "xlou.iam.xlou-cdm.engineeringpit.com", "tool_name": "gatling" } ________________________________________________________________________________ [2024-04-24 09:55:24] Reporting-mixedquery-10 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2024-04-24 09:55:24] Reporting-mixedquery-10 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/01_mixedquery-10/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2024-04-24 09:55:24] Reporting-mixedquery-10 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped