--Task-- name: Reporting-mixedquery-20 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'] preceding_task: None options: {'task_name': 'mixedquery-20'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 09:55:24] Reporting-mixedquery-20 pre : N/A ________________________________________________________________________________ source (overseer-0) is a component [2024-04-24 09:55:24] - INFO: Timeout for this Task has changed to 2h (7200 seconds) [2024-04-24 09:55:24] - 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-20/stdout.txt Pod stderr file : /results/orders/Reporting-mixedquery-20/stderr.txt Pod log : /results/orders/Reporting-mixedquery-20 ________________________________________________________________________________ [2024-04-24 09:55:24] Reporting-mixedquery-20 step1 : Write order for overseer to generate the report ________________________________________________________________________________ 2024-04-24 09:55:24,571 INFO 2024-04-24 09:55:24,571 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:24,571 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:24,926 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:24,926 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:24,926 DEBUG --- stdout --- 2024-04-24 09:55:24,926 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:24,926 DEBUG --- stderr --- 2024-04-24 09:55:24,926 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/10_Reporting-mixedquery-20/order.json { "name": "Reporting-mixedquery-20", "command": "/lodestar/clients/gatling-report-only-for-pyrock.sh mixedquery-20 --extra-run-jvm-options \"-Xmx12G\"", "out-dir": "/results/orders/Reporting-mixedquery-20" } ________________________________________________________________________________ [2024-04-24 09:55:24] Reporting-mixedquery-20 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2024-04-24 09:55:24,930 INFO 2024-04-24 09:55:24,930 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:55:24,930 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2024-04-24 09:55:25,265 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:55:25,265 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:25,265 DEBUG --- stdout --- 2024-04-24 09:55:25,265 DEBUG WAITING 2024-04-24 09:55:25,265 DEBUG --- stderr --- 2024-04-24 09:55:25,265 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 24 0 --:--:-- --:--:-- --:--:-- 24 ________________________________________________________________________________ [2024-04-24 09:55:25] Reporting-mixedquery-20 step3 : Send order to overseer ________________________________________________________________________________ 2024-04-24 09:55:25,269 INFO 2024-04-24 09:55:25,270 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:25,270 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:25,659 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:25,659 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:25,659 DEBUG --- stdout --- 2024-04-24 09:55:25,659 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:25,659 DEBUG --- stderr --- 2024-04-24 09:55:25,659 DEBUG 2024-04-24 09:55:25,660 INFO 2024-04-24 09:55:25,660 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/10_Reporting-mixedquery-20/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 09:55:25,660 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:55:27,089 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:27,089 DEBUG --- stdout --- 2024-04-24 09:55:27,089 DEBUG 2024-04-24 09:55:27,089 DEBUG --- stderr --- 2024-04-24 09:55:27,089 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2024-04-24 09:55:27] Reporting-mixedquery-20 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2024-04-24 09:55:32,095 INFO 2024-04-24 09:55:32,095 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:55:32,095 INFO [loop_until]: (max_time=7200, interval=5, expected_rc=[0] 2024-04-24 09:55:32,347 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2024-04-24 09:55:37,609 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2024-04-24 09:55:37,609 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:37,609 DEBUG --- stdout --- 2024-04-24 09:55:37,609 DEBUG WAITING 2024-04-24 09:55:37,609 DEBUG --- stderr --- 2024-04-24 09:55:37,609 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 32 0 --:--:-- --:--:-- --:--:-- 31 ________________________________________________________________________________ [2024-04-24 09:55:37] Reporting-mixedquery-20 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2024-04-24 09:55:37,610 INFO 2024-04-24 09:55:37,610 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:37,611 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:37,943 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:37,943 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:37,943 DEBUG --- stdout --- 2024-04-24 09:55:37,943 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:37,943 DEBUG --- stderr --- 2024-04-24 09:55:37,944 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:55:37,944 INFO 2024-04-24 09:55:37,944 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:37,944 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:38,296 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:38,297 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:38,297 DEBUG --- stdout --- 2024-04-24 09:55:38,297 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:38,297 DEBUG --- stderr --- 2024-04-24 09:55:38,297 DEBUG 2024-04-24 09:55:38,297 INFO 2024-04-24 09:55:38,297 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-20.tar.gz" ]; then echo "/results/gatling/mixedquery-20.tar.gz file found"; exit 0; else echo "/results/gatling/mixedquery-20.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/mixedquery-20.tar.gz file found" 2024-04-24 09:55:38,297 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:55:39,150 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:55:39,150 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:39,150 DEBUG --- stdout --- 2024-04-24 09:55:39,150 DEBUG /results/gatling/mixedquery-20.tar.gz file found 2024-04-24 09:55:39,150 DEBUG --- stderr --- 2024-04-24 09:55:39,150 DEBUG 2024-04-24 09:55:39,151 INFO 2024-04-24 09:55:39,151 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:39,151 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:39,476 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:39,477 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:39,477 DEBUG --- stdout --- 2024-04-24 09:55:39,477 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:39,477 DEBUG --- stderr --- 2024-04-24 09:55:39,477 DEBUG 2024-04-24 09:55:39,477 INFO 2024-04-24 09:55:39,477 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-20.tar.gz 2024-04-24 09:55:40,332 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:55:40,332 DEBUG --- stdout --- 2024-04-24 09:55:40,332 DEBUG 336 /results/gatling/mixedquery-20.tar.gz 2024-04-24 09:55:40,332 DEBUG --- stderr --- 2024-04-24 09:55:40,332 DEBUG 2024-04-24 09:55:40,332 INFO - Download file (size 336k) 2024-04-24 09:55:40,333 INFO 2024-04-24 09:55:40,333 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:40,333 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:40,670 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:40,671 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:40,671 DEBUG --- stdout --- 2024-04-24 09:55:40,671 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:40,671 DEBUG --- stderr --- 2024-04-24 09:55:40,671 DEBUG source : /results/gatling/mixedquery-20.tar.gz @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-20.tar.gz 2024-04-24 09:55:40,671 INFO 2024-04-24 09:55:40,672 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:55:40,672 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:41,021 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:41,021 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:41,021 DEBUG --- stdout --- 2024-04-24 09:55:41,021 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:41,021 DEBUG --- stderr --- 2024-04-24 09:55:41,021 DEBUG 2024-04-24 09:55:41,022 INFO 2024-04-24 09:55:41,022 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/gatling/mixedquery-20.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-20.tar.gz 2024-04-24 09:55:41,022 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:55:42,114 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:42,114 DEBUG --- stdout --- 2024-04-24 09:55:42,114 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:55:42,114 DEBUG --- stderr --- 2024-04-24 09:55:42,115 DEBUG ________________________________________________________________________________ [2024-04-24 09:55:42] Reporting-mixedquery-20 step6 : Unzip the result ________________________________________________________________________________ 2024-04-24 09:55:42,116 INFO 2024-04-24 09:55:42,116 INFO [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-20.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/gatling 2024-04-24 09:55:42,156 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:55:42,156 DEBUG --- stdout --- 2024-04-24 09:55:42,156 DEBUG 2024-04-24 09:55:42,156 DEBUG --- stderr --- 2024-04-24 09:55:42,156 DEBUG 2024-04-24 09:55:42,156 INFO ________________________________________________________________________________ [2024-04-24 09:55:42] Reporting-mixedquery-20 step7 : Print rc order ________________________________________________________________________________ 2024-04-24 09:55:42,157 INFO 2024-04-24 09:55:42,157 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:42,157 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:42,486 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:42,487 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:42,487 DEBUG --- stdout --- 2024-04-24 09:55:42,487 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:42,487 DEBUG --- stderr --- 2024-04-24 09:55:42,487 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:55:42,487 INFO 2024-04-24 09:55:42,487 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:42,487 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:42,847 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:42,848 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:42,848 DEBUG --- stdout --- 2024-04-24 09:55:42,848 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:42,848 DEBUG --- stderr --- 2024-04-24 09:55:42,848 DEBUG 2024-04-24 09:55:42,849 INFO 2024-04-24 09:55:42,849 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-20/rc.txt" ]; then echo "/results/orders/Reporting-mixedquery-20/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-20/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-20/rc.txt file found" 2024-04-24 09:55:42,849 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:55:43,699 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:55:43,699 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:43,699 DEBUG --- stdout --- 2024-04-24 09:55:43,699 DEBUG /results/orders/Reporting-mixedquery-20/rc.txt file found 2024-04-24 09:55:43,699 DEBUG --- stderr --- 2024-04-24 09:55:43,699 DEBUG 2024-04-24 09:55:43,699 INFO 2024-04-24 09:55:43,700 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:43,700 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:44,035 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:44,035 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:44,035 DEBUG --- stdout --- 2024-04-24 09:55:44,035 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:44,035 DEBUG --- stderr --- 2024-04-24 09:55:44,035 DEBUG 2024-04-24 09:55:44,036 INFO 2024-04-24 09:55:44,036 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-20/rc.txt 2024-04-24 09:55:44,900 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:55:44,901 DEBUG --- stdout --- 2024-04-24 09:55:44,901 DEBUG 4 /results/orders/Reporting-mixedquery-20/rc.txt 2024-04-24 09:55:44,901 DEBUG --- stderr --- 2024-04-24 09:55:44,901 DEBUG 2024-04-24 09:55:44,901 INFO - Download file (size 4k) 2024-04-24 09:55:44,901 INFO 2024-04-24 09:55:44,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:55:44,901 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:45,265 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:45,266 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:45,266 DEBUG --- stdout --- 2024-04-24 09:55:45,266 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:45,266 DEBUG --- stderr --- 2024-04-24 09:55:45,266 DEBUG source : /results/orders/Reporting-mixedquery-20/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/10_Reporting-mixedquery-20/rc.txt 2024-04-24 09:55:45,266 INFO 2024-04-24 09:55:45,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:55:45,267 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:45,600 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:45,600 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:45,600 DEBUG --- stdout --- 2024-04-24 09:55:45,600 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:45,600 DEBUG --- stderr --- 2024-04-24 09:55:45,600 DEBUG 2024-04-24 09:55:45,601 INFO 2024-04-24 09:55:45,601 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-20/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/10_Reporting-mixedquery-20/rc.txt 2024-04-24 09:55:45,601 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:55:46,415 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:46,416 DEBUG --- stdout --- 2024-04-24 09:55:46,416 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:55:46,416 DEBUG --- stderr --- 2024-04-24 09:55:46,416 DEBUG 2024-04-24 09:55:46,416 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/10_Reporting-mixedquery-20/rc.txt is small (1 lines), printing all lines: 2024-04-24 09:55:46,416 DEBUG ----- output ----- 2024-04-24 09:55:46,416 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 09:55:46,416 INFO ________________________________________________________________________________ [2024-04-24 09:55:46] Reporting-mixedquery-20 step8 : Check rc log ________________________________________________________________________________ PASS: no error found in rc log ________________________________________________________________________________ [2024-04-24 09:55:46] Reporting-mixedquery-20 step9 : Print stdout order ________________________________________________________________________________ 2024-04-24 09:55:46,418 INFO 2024-04-24 09:55:46,418 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:46,418 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:46,775 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:46,775 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:46,775 DEBUG --- stdout --- 2024-04-24 09:55:46,775 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:46,775 DEBUG --- stderr --- 2024-04-24 09:55:46,775 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:55:46,776 INFO 2024-04-24 09:55:46,776 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:46,776 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:47,259 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:47,259 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:47,270 DEBUG --- stdout --- 2024-04-24 09:55:47,271 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:47,271 DEBUG --- stderr --- 2024-04-24 09:55:47,271 DEBUG 2024-04-24 09:55:47,273 INFO 2024-04-24 09:55:47,273 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-20/stdout.txt" ]; then echo "/results/orders/Reporting-mixedquery-20/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-20/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-20/stdout.txt file found" 2024-04-24 09:55:47,273 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:55:48,177 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:55:48,177 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:48,177 DEBUG --- stdout --- 2024-04-24 09:55:48,177 DEBUG /results/orders/Reporting-mixedquery-20/stdout.txt file found 2024-04-24 09:55:48,177 DEBUG --- stderr --- 2024-04-24 09:55:48,177 DEBUG 2024-04-24 09:55:48,178 INFO 2024-04-24 09:55:48,178 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:48,178 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:48,556 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:48,557 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:48,557 DEBUG --- stdout --- 2024-04-24 09:55:48,557 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:48,557 DEBUG --- stderr --- 2024-04-24 09:55:48,557 DEBUG 2024-04-24 09:55:48,557 INFO 2024-04-24 09:55:48,558 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-20/stdout.txt 2024-04-24 09:55:49,567 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:55:49,568 DEBUG --- stdout --- 2024-04-24 09:55:49,568 DEBUG 4 /results/orders/Reporting-mixedquery-20/stdout.txt 2024-04-24 09:55:49,568 DEBUG --- stderr --- 2024-04-24 09:55:49,568 DEBUG 2024-04-24 09:55:49,568 INFO - Download file (size 4k) 2024-04-24 09:55:49,568 INFO 2024-04-24 09:55:49,568 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:49,568 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:49,913 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:49,913 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:49,913 DEBUG --- stdout --- 2024-04-24 09:55:49,913 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:49,913 DEBUG --- stderr --- 2024-04-24 09:55:49,913 DEBUG source : /results/orders/Reporting-mixedquery-20/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/10_Reporting-mixedquery-20/stdout.txt 2024-04-24 09:55:49,914 INFO 2024-04-24 09:55:49,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:49,914 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:50,243 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:50,243 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:50,243 DEBUG --- stdout --- 2024-04-24 09:55:50,243 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:50,243 DEBUG --- stderr --- 2024-04-24 09:55:50,243 DEBUG 2024-04-24 09:55:50,244 INFO 2024-04-24 09:55:50,244 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-20/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/10_Reporting-mixedquery-20/stdout.txt 2024-04-24 09:55:50,244 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:55:51,052 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:51,053 DEBUG --- stdout --- 2024-04-24 09:55:51,053 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:55:51,053 DEBUG --- stderr --- 2024-04-24 09:55:51,053 DEBUG 2024-04-24 09:55:51,053 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/10_Reporting-mixedquery-20/stdout.txt is small (81 lines), printing all lines: 2024-04-24 09:55:51,053 DEBUG ----- output ----- 2024-04-24 09:55:51,053 DEBUG [cmd] /lodestar/clients/gatling/bin/gatling.sh --reports-only /results/gatling/mixedquery-20/loadtest-20240424060620549 --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 35505 (OK=35505 KO=0 ) > min response time 5 (OK=5 KO=- ) > max response time 1052 (OK=1052 KO=- ) > mean response time 95 (OK=95 KO=- ) > std deviation 112 (OK=112 KO=- ) > response time 50th percentile 8 (OK=8 KO=- ) > response time 75th percentile 215 (OK=215 KO=- ) > response time 95th percentile 272 (OK=272 KO=- ) > response time 99th percentile 311 (OK=311 KO=- ) > mean requests/sec 19.391 (OK=19.391 KO=- ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 35502 (100%) > 800 ms <= t < 1200 ms 3 ( 0%) > t >= 1200 ms 0 ( 0%) > failed 0 ( 0%) ================================================================================ Reports generated in 0s. Please open the following file: file:///results/gatling/mixedquery-20/loadtest-20240424060620549/index.html ---------------------- [24/Apr/2024 10:55:32] Create tar file /results/gatling/mixedquery-20.tar.gz ---------------------- SIMULATION_PATH=/results/gatling/mixedquery-20/loadtest-20240424060620549 RESULT_FOLDER=mixedquery-20 RESULT_PATH=/results/gatling/mixedquery-20 PWD=/results/gatling/mixedquery-20/loadtest-20240424060620549 [cmd] tar -czvf /results/gatling/mixedquery-20.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:51,053 INFO ________________________________________________________________________________ [2024-04-24 09:55:51] Reporting-mixedquery-20 step10 : Check stdout log ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2024-04-24 09:55:51] Reporting-mixedquery-20 step11 : Print stderr order ________________________________________________________________________________ 2024-04-24 09:55:51,055 INFO 2024-04-24 09:55:51,055 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:51,055 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:51,442 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:51,442 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:51,442 DEBUG --- stdout --- 2024-04-24 09:55:51,443 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:51,443 DEBUG --- stderr --- 2024-04-24 09:55:51,443 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:55:51,443 INFO 2024-04-24 09:55:51,443 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:51,443 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:51,774 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:51,775 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:51,775 DEBUG --- stdout --- 2024-04-24 09:55:51,775 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:51,775 DEBUG --- stderr --- 2024-04-24 09:55:51,775 DEBUG 2024-04-24 09:55:51,776 INFO 2024-04-24 09:55:51,776 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-20/stderr.txt" ]; then echo "/results/orders/Reporting-mixedquery-20/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-20/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-20/stderr.txt file found" 2024-04-24 09:55:51,776 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:55:52,639 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:55:52,640 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:52,640 DEBUG --- stdout --- 2024-04-24 09:55:52,640 DEBUG /results/orders/Reporting-mixedquery-20/stderr.txt file found 2024-04-24 09:55:52,640 DEBUG --- stderr --- 2024-04-24 09:55:52,640 DEBUG 2024-04-24 09:55:52,640 INFO 2024-04-24 09:55:52,640 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:52,640 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:52,964 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:52,964 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:52,965 DEBUG --- stdout --- 2024-04-24 09:55:52,965 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:52,965 DEBUG --- stderr --- 2024-04-24 09:55:52,965 DEBUG 2024-04-24 09:55:52,965 INFO 2024-04-24 09:55:52,965 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-20/stderr.txt 2024-04-24 09:55:53,819 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:55:53,819 DEBUG --- stdout --- 2024-04-24 09:55:53,820 DEBUG 0 /results/orders/Reporting-mixedquery-20/stderr.txt 2024-04-24 09:55:53,820 DEBUG --- stderr --- 2024-04-24 09:55:53,820 DEBUG 2024-04-24 09:55:53,820 INFO - Download file (size 0k) 2024-04-24 09:55:53,820 INFO 2024-04-24 09:55:53,821 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:53,821 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:54,165 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:54,165 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:54,166 DEBUG --- stdout --- 2024-04-24 09:55:54,166 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:54,166 DEBUG --- stderr --- 2024-04-24 09:55:54,166 DEBUG source : /results/orders/Reporting-mixedquery-20/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/10_Reporting-mixedquery-20/stderr.txt 2024-04-24 09:55:54,166 INFO 2024-04-24 09:55:54,166 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:54,166 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:55:54,494 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:55:54,494 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:54,494 DEBUG --- stdout --- 2024-04-24 09:55:54,494 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:55:54,494 DEBUG --- stderr --- 2024-04-24 09:55:54,494 DEBUG 2024-04-24 09:55:54,495 INFO 2024-04-24 09:55:54,495 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-20/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/10_Reporting-mixedquery-20/stderr.txt 2024-04-24 09:55:54,495 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:55:55,260 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:55:55,260 DEBUG --- stdout --- 2024-04-24 09:55:55,260 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:55:55,260 DEBUG --- stderr --- 2024-04-24 09:55:55,260 DEBUG 2024-04-24 09:55:55,261 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/10_Reporting-mixedquery-20/stderr.txt is small (0 lines), printing all lines: 2024-04-24 09:55:55,261 DEBUG ----- output ----- 2024-04-24 09:55:55,261 DEBUG 2024-04-24 09:55:55,261 INFO ________________________________________________________________________________ [2024-04-24 09:55:55] Reporting-mixedquery-20 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-24 09:55:55] Reporting-mixedquery-20 step13 : Analyze graphs for spikes ________________________________________________________________________________ Analyze Gatling graphs for spikes checking parameters open overall stats JSON file checking simulation request AdminRestLogin checking simulation request AdminAuthorize checking simulation request Admin AccessToken stage checking simulation request queryFull checking simulation request queryBySn checking simulation request queryStartsWith checking simulation request queryPartial checking simulation request ReadManagedUser no spikes found ________________________________________________________________________________ [2024-04-24 09:55:55] Reporting-mixedquery-20 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 35505, "ok": 35505, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 1052, "ok": 1052, "ko": 0 }, "meanResponseTime": { "total": 95, "ok": 95, "ko": 0 }, "standardDeviation": { "total": 112, "ok": 112, "ko": 0 }, "percentiles1": { "total": 8, "ok": 8, "ko": 0 }, "percentiles2": { "total": 215, "ok": 215, "ko": 0 }, "percentiles3": { "total": 272, "ok": 272, "ko": 0 }, "percentiles4": { "total": 311, "ok": 311, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 35502, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 3, "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": 19.391043145821957, "ok": 19.391043145821957, "ko": 0 } }, "details": [ { "name": "AdminRestLogin", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 40, "ok": 40, "ko": 0 }, "maxResponseTime": { "total": 40, "ok": 40, "ko": 0 }, "meanResponseTime": { "total": 40, "ok": 40, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 40, "ok": 40, "ko": 0 }, "percentiles2": { "total": 40, "ok": 40, "ko": 0 }, "percentiles3": { "total": 40, "ok": 40, "ko": 0 }, "percentiles4": { "total": 40, "ok": 40, "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": 63, "ok": 63, "ko": 0 }, "maxResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "meanResponseTime": { "total": 63, "ok": 63, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 63, "ok": 63, "ko": 0 }, "percentiles2": { "total": 63, "ok": 63, "ko": 0 }, "percentiles3": { "total": 63, "ok": 63, "ko": 0 }, "percentiles4": { "total": 63, "ok": 63, "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": 222, "ok": 222, "ko": 0 }, "maxResponseTime": { "total": 222, "ok": 222, "ko": 0 }, "meanResponseTime": { "total": 222, "ok": 222, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 222, "ok": 222, "ko": 0 }, "percentiles2": { "total": 222, "ok": 222, "ko": 0 }, "percentiles3": { "total": 222, "ok": 222, "ko": 0 }, "percentiles4": { "total": 222, "ok": 222, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005461496450027307, "ok": 0.0005461496450027307, "ko": 0 }, "spikes": [] }, { "name": "queryFull", "numberOfRequests": { "total": 7261, "ok": 7261, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 1052, "ok": 1052, "ko": 0 }, "meanResponseTime": { "total": 8, "ok": 8, "ko": 0 }, "standardDeviation": { "total": 12, "ok": 12, "ko": 0 }, "percentiles1": { "total": 7, "ok": 7, "ko": 0 }, "percentiles2": { "total": 8, "ok": 8, "ko": 0 }, "percentiles3": { "total": 9, "ok": 9, "ko": 0 }, "percentiles4": { "total": 11, "ok": 11, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 7260, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 1, "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": 3.965592572364828, "ok": 3.965592572364828, "ko": 0 }, "spikes": [] }, { "name": "queryBySn", "numberOfRequests": { "total": 6988, "ok": 6988, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 68, "ok": 68, "ko": 0 }, "meanResponseTime": { "total": 7, "ok": 7, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 7, "ok": 7, "ko": 0 }, "percentiles2": { "total": 8, "ok": 8, "ko": 0 }, "percentiles3": { "total": 9, "ok": 9, "ko": 0 }, "percentiles4": { "total": 11, "ok": 11, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 6988, "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": 3.8164937192790824, "ok": 3.8164937192790824, "ko": 0 }, "spikes": [] }, { "name": "queryStartsWith", "numberOfRequests": { "total": 7094, "ok": 7094, "ko": 0 }, "minResponseTime": { "total": 85, "ok": 85, "ko": 0 }, "maxResponseTime": { "total": 357, "ok": 357, "ko": 0 }, "meanResponseTime": { "total": 226, "ok": 226, "ko": 0 }, "standardDeviation": { "total": 44, "ok": 44, "ko": 0 }, "percentiles1": { "total": 231, "ok": 231, "ko": 0 }, "percentiles2": { "total": 255, "ok": 255, "ko": 0 }, "percentiles3": { "total": 287, "ok": 287, "ko": 0 }, "percentiles4": { "total": 308, "ok": 308, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 7094, "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": 3.874385581649372, "ok": 3.874385581649372, "ko": 0 }, "spikes": [] }, { "name": "queryPartial", "numberOfRequests": { "total": 7059, "ok": 7059, "ko": 0 }, "minResponseTime": { "total": 161, "ok": 161, "ko": 0 }, "maxResponseTime": { "total": 393, "ok": 393, "ko": 0 }, "meanResponseTime": { "total": 231, "ok": 231, "ko": 0 }, "standardDeviation": { "total": 37, "ok": 37, "ko": 0 }, "percentiles1": { "total": 223, "ok": 223, "ko": 0 }, "percentiles2": { "total": 249, "ok": 249, "ko": 0 }, "percentiles3": { "total": 306, "ok": 306, "ko": 0 }, "percentiles4": { "total": 342, "ok": 342, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 7059, "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": 3.855270344074276, "ok": 3.855270344074276, "ko": 0 }, "spikes": [] }, { "name": "ReadManagedUser", "numberOfRequests": { "total": 7100, "ok": 7100, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 1045, "ok": 1045, "ko": 0 }, "meanResponseTime": { "total": 7, "ok": 7, "ko": 0 }, "standardDeviation": { "total": 18, "ok": 18, "ko": 0 }, "percentiles1": { "total": 6, "ok": 6, "ko": 0 }, "percentiles2": { "total": 7, "ok": 7, "ko": 0 }, "percentiles3": { "total": 7, "ok": 7, "ko": 0 }, "percentiles4": { "total": 9, "ok": 9, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 7098, "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": 3.877662479519388, "ok": 3.877662479519388, "ko": 0 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 19, "avg_response_time": 95, "max_response_time": 1052, "min_response_time": 5, "num_requests": 35505, "num_requests_pass": 35505, "num_requests_percent_pass": 100.0 }, "options": "; duration:1800; simulation:com.forgerock.gatling.Loadtest; testCase:idm.MixedQuery; testMode:open; throughput:20", "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": 40, "total": 40 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 40, "total": 40 }, "minResponseTime": { "ko": 0, "ok": 40, "total": 40 }, "name": "AdminRestLogin", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 40, "total": 40 }, "percentiles2": { "ko": 0, "ok": 40, "total": 40 }, "percentiles3": { "ko": 0, "ok": 40, "total": 40 }, "percentiles4": { "ko": 0, "ok": 40, "total": 40 }, "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": 63, "total": 63 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "minResponseTime": { "ko": 0, "ok": 63, "total": 63 }, "name": "AdminAuthorize", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 63, "total": 63 }, "percentiles2": { "ko": 0, "ok": 63, "total": 63 }, "percentiles3": { "ko": 0, "ok": 63, "total": 63 }, "percentiles4": { "ko": 0, "ok": 63, "total": 63 }, "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": 222, "total": 222 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 222, "total": 222 }, "minResponseTime": { "ko": 0, "ok": 222, "total": 222 }, "name": "Admin AccessToken stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 222, "total": 222 }, "percentiles2": { "ko": 0, "ok": 222, "total": 222 }, "percentiles3": { "ko": 0, "ok": 222, "total": 222 }, "percentiles4": { "ko": 0, "ok": 222, "total": 222 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 7260, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 1, "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": 1052, "total": 1052 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 3.965592572364828, "total": 3.965592572364828 }, "meanResponseTime": { "ko": 0, "ok": 8, "total": 8 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryFull", "numberOfRequests": { "ko": 0, "ok": 7261, "total": 7261 }, "percentiles1": { "ko": 0, "ok": 7, "total": 7 }, "percentiles2": { "ko": 0, "ok": 8, "total": 8 }, "percentiles3": { "ko": 0, "ok": 9, "total": 9 }, "percentiles4": { "ko": 0, "ok": 11, "total": 11 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 12, "total": 12 } }, { "group1": { "count": 6988, "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": 68, "total": 68 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 3.8164937192790824, "total": 3.8164937192790824 }, "meanResponseTime": { "ko": 0, "ok": 7, "total": 7 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryBySn", "numberOfRequests": { "ko": 0, "ok": 6988, "total": 6988 }, "percentiles1": { "ko": 0, "ok": 7, "total": 7 }, "percentiles2": { "ko": 0, "ok": 8, "total": 8 }, "percentiles3": { "ko": 0, "ok": 9, "total": 9 }, "percentiles4": { "ko": 0, "ok": 11, "total": 11 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } }, { "group1": { "count": 7094, "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": 357, "total": 357 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 3.874385581649372, "total": 3.874385581649372 }, "meanResponseTime": { "ko": 0, "ok": 226, "total": 226 }, "minResponseTime": { "ko": 0, "ok": 85, "total": 85 }, "name": "queryStartsWith", "numberOfRequests": { "ko": 0, "ok": 7094, "total": 7094 }, "percentiles1": { "ko": 0, "ok": 231, "total": 231 }, "percentiles2": { "ko": 0, "ok": 255, "total": 255 }, "percentiles3": { "ko": 0, "ok": 287, "total": 287 }, "percentiles4": { "ko": 0, "ok": 308, "total": 308 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 44, "total": 44 } }, { "group1": { "count": 7059, "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": 3.855270344074276, "total": 3.855270344074276 }, "meanResponseTime": { "ko": 0, "ok": 231, "total": 231 }, "minResponseTime": { "ko": 0, "ok": 161, "total": 161 }, "name": "queryPartial", "numberOfRequests": { "ko": 0, "ok": 7059, "total": 7059 }, "percentiles1": { "ko": 0, "ok": 223, "total": 223 }, "percentiles2": { "ko": 0, "ok": 249, "total": 249 }, "percentiles3": { "ko": 0, "ok": 306, "total": 306 }, "percentiles4": { "ko": 0, "ok": 342, "total": 342 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 37, "total": 37 } }, { "group1": { "count": 7098, "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": 1045, "total": 1045 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 3.877662479519388, "total": 3.877662479519388 }, "meanResponseTime": { "ko": 0, "ok": 7, "total": 7 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "ReadManagedUser", "numberOfRequests": { "ko": 0, "ok": 7100, "total": 7100 }, "percentiles1": { "ko": 0, "ok": 6, "total": 6 }, "percentiles2": { "ko": 0, "ok": 7, "total": 7 }, "percentiles3": { "ko": 0, "ok": 7, "total": 7 }, "percentiles4": { "ko": 0, "ok": 9, "total": 9 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 18, "total": 18 } } ], "global": { "group1": { "count": 35502, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 3, "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": 1052, "total": 1052 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 19.391043145821957, "total": 19.391043145821957 }, "meanResponseTime": { "ko": 0, "ok": 95, "total": 95 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "All Requests", "numberOfRequests": { "ko": 0, "ok": 35505, "total": 35505 }, "percentiles1": { "ko": 0, "ok": 8, "total": 8 }, "percentiles2": { "ko": 0, "ok": 215, "total": 215 }, "percentiles3": { "ko": 0, "ok": 272, "total": 272 }, "percentiles4": { "ko": 0, "ok": 311, "total": 311 }, "standardDeviation": { "ko": 0, "ok": 112, "total": 112 } } }, "target_hostname": "xlou.iam.xlou-cdm.engineeringpit.com", "tool_name": "gatling" } ________________________________________________________________________________ [2024-04-24 09:55:55] Reporting-mixedquery-20 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2024-04-24 09:55:55] Reporting-mixedquery-20 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/02_mixedquery-20/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2024-04-24 09:55:55] Reporting-mixedquery-20 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped