--Task-- name: Reporting-mixedquery-40 enabled: True class_name: GatlingResultTask source_name: overseer-0 source_namespace: >default< target_name: overseer-0 target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['Reporting-mixedquery-10', 'Reporting-mixedquery-20', 'Reporting-mixedquery-30'] preceding_task: None options: {'task_name': 'mixedquery-40'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 09:56:25] Reporting-mixedquery-40 pre : N/A ________________________________________________________________________________ source (overseer-0) is a component [2024-04-24 09:56:25] - INFO: Timeout for this Task has changed to 2h (7200 seconds) [2024-04-24 09:56:25] - 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-40/stdout.txt Pod stderr file : /results/orders/Reporting-mixedquery-40/stderr.txt Pod log : /results/orders/Reporting-mixedquery-40 ________________________________________________________________________________ [2024-04-24 09:56:25] Reporting-mixedquery-40 step1 : Write order for overseer to generate the report ________________________________________________________________________________ 2024-04-24 09:56:25,686 INFO 2024-04-24 09:56:25,686 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:25,686 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:26,093 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:26,093 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:26,094 DEBUG --- stdout --- 2024-04-24 09:56:26,094 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:26,094 DEBUG --- stderr --- 2024-04-24 09:56:26,094 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/12_Reporting-mixedquery-40/order.json { "name": "Reporting-mixedquery-40", "command": "/lodestar/clients/gatling-report-only-for-pyrock.sh mixedquery-40 --extra-run-jvm-options \"-Xmx12G\"", "out-dir": "/results/orders/Reporting-mixedquery-40" } ________________________________________________________________________________ [2024-04-24 09:56:26] Reporting-mixedquery-40 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2024-04-24 09:56:26,095 INFO 2024-04-24 09:56:26,096 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:56:26,096 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2024-04-24 09:56:26,411 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:56:26,411 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:26,411 DEBUG --- stdout --- 2024-04-24 09:56:26,411 DEBUG WAITING 2024-04-24 09:56:26,411 DEBUG --- stderr --- 2024-04-24 09:56:26,411 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 25 0 --:--:-- --:--:-- --:--:-- 25 ________________________________________________________________________________ [2024-04-24 09:56:26] Reporting-mixedquery-40 step3 : Send order to overseer ________________________________________________________________________________ 2024-04-24 09:56:26,412 INFO 2024-04-24 09:56:26,412 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:26,412 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:26,770 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:26,771 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:26,771 DEBUG --- stdout --- 2024-04-24 09:56:26,771 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:26,771 DEBUG --- stderr --- 2024-04-24 09:56:26,771 DEBUG 2024-04-24 09:56:26,771 INFO 2024-04-24 09:56:26,772 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/12_Reporting-mixedquery-40/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 09:56:26,772 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:56:28,183 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:28,184 DEBUG --- stdout --- 2024-04-24 09:56:28,184 DEBUG 2024-04-24 09:56:28,184 DEBUG --- stderr --- 2024-04-24 09:56:28,184 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2024-04-24 09:56:28] Reporting-mixedquery-40 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2024-04-24 09:56:33,187 INFO 2024-04-24 09:56:33,187 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:56:33,187 INFO [loop_until]: (max_time=7200, interval=5, expected_rc=[0] 2024-04-24 09:56:33,432 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2024-04-24 09:56:38,691 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2024-04-24 09:56:38,691 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:38,692 DEBUG --- stdout --- 2024-04-24 09:56:38,692 DEBUG WAITING 2024-04-24 09:56:38,692 DEBUG --- stderr --- 2024-04-24 09:56:38,692 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-24 09:56:38] Reporting-mixedquery-40 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2024-04-24 09:56:38,693 INFO 2024-04-24 09:56:38,693 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:38,693 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:39,041 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:39,041 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:39,041 DEBUG --- stdout --- 2024-04-24 09:56:39,041 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:39,042 DEBUG --- stderr --- 2024-04-24 09:56:39,042 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:56:39,042 INFO 2024-04-24 09:56:39,042 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:39,042 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:39,380 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:39,380 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:39,380 DEBUG --- stdout --- 2024-04-24 09:56:39,380 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:39,380 DEBUG --- stderr --- 2024-04-24 09:56:39,380 DEBUG 2024-04-24 09:56:39,381 INFO 2024-04-24 09:56:39,381 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-40.tar.gz" ]; then echo "/results/gatling/mixedquery-40.tar.gz file found"; exit 0; else echo "/results/gatling/mixedquery-40.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/mixedquery-40.tar.gz file found" 2024-04-24 09:56:39,381 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:56:40,241 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:56:40,242 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:40,242 DEBUG --- stdout --- 2024-04-24 09:56:40,242 DEBUG /results/gatling/mixedquery-40.tar.gz file found 2024-04-24 09:56:40,242 DEBUG --- stderr --- 2024-04-24 09:56:40,242 DEBUG 2024-04-24 09:56:40,242 INFO 2024-04-24 09:56:40,242 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:40,242 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:40,575 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:40,575 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:40,575 DEBUG --- stdout --- 2024-04-24 09:56:40,575 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:40,575 DEBUG --- stderr --- 2024-04-24 09:56:40,575 DEBUG 2024-04-24 09:56:40,575 INFO 2024-04-24 09:56:40,576 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-40.tar.gz 2024-04-24 09:56:41,463 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:56:41,464 DEBUG --- stdout --- 2024-04-24 09:56:41,464 DEBUG 344 /results/gatling/mixedquery-40.tar.gz 2024-04-24 09:56:41,464 DEBUG --- stderr --- 2024-04-24 09:56:41,464 DEBUG 2024-04-24 09:56:41,464 INFO - Download file (size 344k) 2024-04-24 09:56:41,464 INFO 2024-04-24 09:56:41,464 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:41,465 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:41,882 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:41,883 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:41,883 DEBUG --- stdout --- 2024-04-24 09:56:41,883 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:41,883 DEBUG --- stderr --- 2024-04-24 09:56:41,883 DEBUG source : /results/gatling/mixedquery-40.tar.gz @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-40.tar.gz 2024-04-24 09:56:41,884 INFO 2024-04-24 09:56:41,884 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:41,884 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:42,261 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:42,262 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:42,262 DEBUG --- stdout --- 2024-04-24 09:56:42,262 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:42,262 DEBUG --- stderr --- 2024-04-24 09:56:42,262 DEBUG 2024-04-24 09:56:42,262 INFO 2024-04-24 09:56:42,263 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/gatling/mixedquery-40.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-40.tar.gz 2024-04-24 09:56:42,263 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:56:43,306 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:43,306 DEBUG --- stdout --- 2024-04-24 09:56:43,306 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:56:43,306 DEBUG --- stderr --- 2024-04-24 09:56:43,307 DEBUG ________________________________________________________________________________ [2024-04-24 09:56:43] Reporting-mixedquery-40 step6 : Unzip the result ________________________________________________________________________________ 2024-04-24 09:56:43,308 INFO 2024-04-24 09:56:43,309 INFO [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-40.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/gatling 2024-04-24 09:56:43,371 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:56:43,371 DEBUG --- stdout --- 2024-04-24 09:56:43,371 DEBUG 2024-04-24 09:56:43,371 DEBUG --- stderr --- 2024-04-24 09:56:43,371 DEBUG 2024-04-24 09:56:43,372 INFO ________________________________________________________________________________ [2024-04-24 09:56:43] Reporting-mixedquery-40 step7 : Print rc order ________________________________________________________________________________ 2024-04-24 09:56:43,373 INFO 2024-04-24 09:56:43,373 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:43,373 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:43,712 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:43,713 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:43,713 DEBUG --- stdout --- 2024-04-24 09:56:43,713 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:43,713 DEBUG --- stderr --- 2024-04-24 09:56:43,713 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:56:43,713 INFO 2024-04-24 09:56:43,713 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:43,713 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:44,053 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:44,053 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:44,053 DEBUG --- stdout --- 2024-04-24 09:56:44,054 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:44,054 DEBUG --- stderr --- 2024-04-24 09:56:44,054 DEBUG 2024-04-24 09:56:44,054 INFO 2024-04-24 09:56:44,054 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-40/rc.txt" ]; then echo "/results/orders/Reporting-mixedquery-40/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-40/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-40/rc.txt file found" 2024-04-24 09:56:44,055 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:56:44,922 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:56:44,923 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:44,923 DEBUG --- stdout --- 2024-04-24 09:56:44,923 DEBUG /results/orders/Reporting-mixedquery-40/rc.txt file found 2024-04-24 09:56:44,923 DEBUG --- stderr --- 2024-04-24 09:56:44,923 DEBUG 2024-04-24 09:56:44,923 INFO 2024-04-24 09:56:44,924 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:44,924 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:45,277 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:45,277 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:45,277 DEBUG --- stdout --- 2024-04-24 09:56:45,277 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:45,277 DEBUG --- stderr --- 2024-04-24 09:56:45,277 DEBUG 2024-04-24 09:56:45,277 INFO 2024-04-24 09:56:45,277 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-40/rc.txt 2024-04-24 09:56:46,150 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:56:46,150 DEBUG --- stdout --- 2024-04-24 09:56:46,150 DEBUG 4 /results/orders/Reporting-mixedquery-40/rc.txt 2024-04-24 09:56:46,150 DEBUG --- stderr --- 2024-04-24 09:56:46,150 DEBUG 2024-04-24 09:56:46,150 INFO - Download file (size 4k) 2024-04-24 09:56:46,151 INFO 2024-04-24 09:56:46,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:56:46,151 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:46,507 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:46,507 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:46,507 DEBUG --- stdout --- 2024-04-24 09:56:46,507 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:46,507 DEBUG --- stderr --- 2024-04-24 09:56:46,507 DEBUG source : /results/orders/Reporting-mixedquery-40/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/12_Reporting-mixedquery-40/rc.txt 2024-04-24 09:56:46,508 INFO 2024-04-24 09:56:46,508 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:46,508 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:47,176 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:47,176 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:47,176 DEBUG --- stdout --- 2024-04-24 09:56:47,176 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:47,176 DEBUG --- stderr --- 2024-04-24 09:56:47,176 DEBUG 2024-04-24 09:56:47,177 INFO 2024-04-24 09:56:47,177 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-40/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/12_Reporting-mixedquery-40/rc.txt 2024-04-24 09:56:47,177 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:56:47,968 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:47,968 DEBUG --- stdout --- 2024-04-24 09:56:47,969 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:56:47,969 DEBUG --- stderr --- 2024-04-24 09:56:47,969 DEBUG 2024-04-24 09:56:47,969 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/12_Reporting-mixedquery-40/rc.txt is small (1 lines), printing all lines: 2024-04-24 09:56:47,969 DEBUG ----- output ----- 2024-04-24 09:56:47,969 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 09:56:47,969 INFO ________________________________________________________________________________ [2024-04-24 09:56:47] Reporting-mixedquery-40 step8 : Check rc log ________________________________________________________________________________ PASS: no error found in rc log ________________________________________________________________________________ [2024-04-24 09:56:47] Reporting-mixedquery-40 step9 : Print stdout order ________________________________________________________________________________ 2024-04-24 09:56:47,971 INFO 2024-04-24 09:56:47,971 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:47,971 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:48,429 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:48,429 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:48,429 DEBUG --- stdout --- 2024-04-24 09:56:48,429 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:48,429 DEBUG --- stderr --- 2024-04-24 09:56:48,429 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:56:48,430 INFO 2024-04-24 09:56:48,430 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:48,430 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:48,796 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:48,797 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:48,797 DEBUG --- stdout --- 2024-04-24 09:56:48,797 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:48,797 DEBUG --- stderr --- 2024-04-24 09:56:48,797 DEBUG 2024-04-24 09:56:48,798 INFO 2024-04-24 09:56:48,798 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-40/stdout.txt" ]; then echo "/results/orders/Reporting-mixedquery-40/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-40/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-40/stdout.txt file found" 2024-04-24 09:56:48,798 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:56:49,651 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:56:49,652 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:49,652 DEBUG --- stdout --- 2024-04-24 09:56:49,652 DEBUG /results/orders/Reporting-mixedquery-40/stdout.txt file found 2024-04-24 09:56:49,652 DEBUG --- stderr --- 2024-04-24 09:56:49,652 DEBUG 2024-04-24 09:56:49,652 INFO 2024-04-24 09:56:49,652 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:49,652 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:50,042 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:50,042 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:50,042 DEBUG --- stdout --- 2024-04-24 09:56:50,042 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:50,043 DEBUG --- stderr --- 2024-04-24 09:56:50,043 DEBUG 2024-04-24 09:56:50,043 INFO 2024-04-24 09:56:50,043 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-40/stdout.txt 2024-04-24 09:56:51,018 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:56:51,018 DEBUG --- stdout --- 2024-04-24 09:56:51,018 DEBUG 4 /results/orders/Reporting-mixedquery-40/stdout.txt 2024-04-24 09:56:51,018 DEBUG --- stderr --- 2024-04-24 09:56:51,018 DEBUG 2024-04-24 09:56:51,018 INFO - Download file (size 4k) 2024-04-24 09:56:51,018 INFO 2024-04-24 09:56:51,019 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:51,019 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:51,356 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:51,357 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:51,357 DEBUG --- stdout --- 2024-04-24 09:56:51,357 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:51,357 DEBUG --- stderr --- 2024-04-24 09:56:51,357 DEBUG source : /results/orders/Reporting-mixedquery-40/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/12_Reporting-mixedquery-40/stdout.txt 2024-04-24 09:56:51,357 INFO 2024-04-24 09:56:51,357 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:51,357 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:51,695 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:51,695 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:51,695 DEBUG --- stdout --- 2024-04-24 09:56:51,695 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:51,695 DEBUG --- stderr --- 2024-04-24 09:56:51,696 DEBUG 2024-04-24 09:56:51,696 INFO 2024-04-24 09:56:51,696 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-40/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/12_Reporting-mixedquery-40/stdout.txt 2024-04-24 09:56:51,697 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:56:52,475 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:52,476 DEBUG --- stdout --- 2024-04-24 09:56:52,476 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:56:52,476 DEBUG --- stderr --- 2024-04-24 09:56:52,476 DEBUG 2024-04-24 09:56:52,476 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/12_Reporting-mixedquery-40/stdout.txt is small (81 lines), printing all lines: 2024-04-24 09:56:52,477 DEBUG ----- output ----- 2024-04-24 09:56:52,477 DEBUG [cmd] /lodestar/clients/gatling/bin/gatling.sh --reports-only /results/gatling/mixedquery-40/loadtest-20240424071131503 --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 72643 (OK=72643 KO=0 ) > min response time 5 (OK=5 KO=- ) > max response time 383 (OK=383 KO=- ) > mean response time 97 (OK=97 KO=- ) > std deviation 111 (OK=111 KO=- ) > response time 50th percentile 8 (OK=8 KO=- ) > response time 75th percentile 223 (OK=223 KO=- ) > response time 95th percentile 261 (OK=261 KO=- ) > response time 99th percentile 286 (OK=286 KO=- ) > mean requests/sec 39.674 (OK=39.674 KO=- ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 72643 (100%) > 800 ms <= t < 1200 ms 0 ( 0%) > t >= 1200 ms 0 ( 0%) > failed 0 ( 0%) ================================================================================ Reports generated in 0s. Please open the following file: file:///results/gatling/mixedquery-40/loadtest-20240424071131503/index.html ---------------------- [24/Apr/2024 10:56:35] Create tar file /results/gatling/mixedquery-40.tar.gz ---------------------- SIMULATION_PATH=/results/gatling/mixedquery-40/loadtest-20240424071131503 RESULT_FOLDER=mixedquery-40 RESULT_PATH=/results/gatling/mixedquery-40 PWD=/results/gatling/mixedquery-40/loadtest-20240424071131503 [cmd] tar -czvf /results/gatling/mixedquery-40.tar.gz --exclude=simulation.log * group_retrievevariabl-5a49b.html group_storevariables-5102b.html index.html js/ js/global_stats.json js/menu.js js/all_sessions.js js/unpack.js js/highcharts-more.js js/jquery-3.5.1.min.js js/bootstrap.min.js js/stats.js js/highstock.js js/ellipsis.js js/assertions.xml js/stats.json js/assertions.json js/gatling.js js/theme.js req_admin-accesstok-f0286.html req_adminauthorize-f6e0c.html req_adminrestlogin-b480c.html req_querybysn-65c9b.html req_queryfull-e5d3d.html req_querypartial-f2acd.html req_querystartswith-1e9cd.html req_readmanageduser-f3f1e.html style/ style/logo-enterprise.svg style/arrow_down_black.png style/arrow_right_black.png style/sorted-down.png style/arrow_down.png style/stat-l-temps.png style/little_arrow_right.png style/favicon.ico style/sortable.png style/arrow_right.png style/logo.svg style/stat-fleche-bas.png style/sorted-up.png style/bootstrap.min.css style/style.css style/stat-l-roue.png 2024-04-24 09:56:52,477 INFO ________________________________________________________________________________ [2024-04-24 09:56:52] Reporting-mixedquery-40 step10 : Check stdout log ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2024-04-24 09:56:52] Reporting-mixedquery-40 step11 : Print stderr order ________________________________________________________________________________ 2024-04-24 09:56:52,479 INFO 2024-04-24 09:56:52,479 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:52,479 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:52,810 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:52,811 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:52,811 DEBUG --- stdout --- 2024-04-24 09:56:52,811 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:52,811 DEBUG --- stderr --- 2024-04-24 09:56:52,811 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:56:52,811 INFO 2024-04-24 09:56:52,811 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:52,811 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:53,155 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:53,155 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:53,155 DEBUG --- stdout --- 2024-04-24 09:56:53,155 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:53,155 DEBUG --- stderr --- 2024-04-24 09:56:53,155 DEBUG 2024-04-24 09:56:53,156 INFO 2024-04-24 09:56:53,157 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-40/stderr.txt" ]; then echo "/results/orders/Reporting-mixedquery-40/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-40/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-40/stderr.txt file found" 2024-04-24 09:56:53,157 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:56:54,021 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:56:54,021 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:54,022 DEBUG --- stdout --- 2024-04-24 09:56:54,022 DEBUG /results/orders/Reporting-mixedquery-40/stderr.txt file found 2024-04-24 09:56:54,022 DEBUG --- stderr --- 2024-04-24 09:56:54,022 DEBUG 2024-04-24 09:56:54,022 INFO 2024-04-24 09:56:54,022 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:54,022 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:54,376 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:54,376 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:54,376 DEBUG --- stdout --- 2024-04-24 09:56:54,376 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:54,376 DEBUG --- stderr --- 2024-04-24 09:56:54,376 DEBUG 2024-04-24 09:56:54,377 INFO 2024-04-24 09:56:54,377 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-40/stderr.txt 2024-04-24 09:56:55,235 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:56:55,235 DEBUG --- stdout --- 2024-04-24 09:56:55,235 DEBUG 0 /results/orders/Reporting-mixedquery-40/stderr.txt 2024-04-24 09:56:55,235 DEBUG --- stderr --- 2024-04-24 09:56:55,236 DEBUG 2024-04-24 09:56:55,236 INFO - Download file (size 0k) 2024-04-24 09:56:55,236 INFO 2024-04-24 09:56:55,236 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:55,236 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:55,609 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:55,610 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:55,610 DEBUG --- stdout --- 2024-04-24 09:56:55,610 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:55,610 DEBUG --- stderr --- 2024-04-24 09:56:55,610 DEBUG source : /results/orders/Reporting-mixedquery-40/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/12_Reporting-mixedquery-40/stderr.txt 2024-04-24 09:56:55,611 INFO 2024-04-24 09:56:55,611 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:56:55,613 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:56:55,995 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:56:55,996 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:55,996 DEBUG --- stdout --- 2024-04-24 09:56:55,996 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:56:55,996 DEBUG --- stderr --- 2024-04-24 09:56:55,996 DEBUG 2024-04-24 09:56:55,997 INFO 2024-04-24 09:56:55,997 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-40/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/12_Reporting-mixedquery-40/stderr.txt 2024-04-24 09:56:55,997 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:56:56,798 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:56:56,798 DEBUG --- stdout --- 2024-04-24 09:56:56,798 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:56:56,798 DEBUG --- stderr --- 2024-04-24 09:56:56,798 DEBUG 2024-04-24 09:56:56,798 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/12_Reporting-mixedquery-40/stderr.txt is small (0 lines), printing all lines: 2024-04-24 09:56:56,799 DEBUG ----- output ----- 2024-04-24 09:56:56,799 DEBUG 2024-04-24 09:56:56,799 INFO ________________________________________________________________________________ [2024-04-24 09:56:56] Reporting-mixedquery-40 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-24 09:56:56] Reporting-mixedquery-40 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 queryStartsWith checking simulation request queryPartial checking simulation request queryBySn checking simulation request queryFull no spikes found ________________________________________________________________________________ [2024-04-24 09:56:56] Reporting-mixedquery-40 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 72643, "ok": 72643, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 383, "ok": 383, "ko": 0 }, "meanResponseTime": { "total": 97, "ok": 97, "ko": 0 }, "standardDeviation": { "total": 111, "ok": 111, "ko": 0 }, "percentiles1": { "total": 8, "ok": 8, "ko": 0 }, "percentiles2": { "total": 223, "ok": 223, "ko": 0 }, "percentiles3": { "total": 261, "ok": 261, "ko": 0 }, "percentiles4": { "total": 286, "ok": 286, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 72643, "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": 39.67394866193337, "ok": 39.67394866193337, "ko": 0 } }, "details": [ { "name": "AdminRestLogin", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 24, "ok": 24, "ko": 0 }, "maxResponseTime": { "total": 24, "ok": 24, "ko": 0 }, "meanResponseTime": { "total": 24, "ok": 24, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 24, "ok": 24, "ko": 0 }, "percentiles2": { "total": 24, "ok": 24, "ko": 0 }, "percentiles3": { "total": 24, "ok": 24, "ko": 0 }, "percentiles4": { "total": 24, "ok": 24, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005461496450027307, "ok": 0.0005461496450027307, "ko": 0 }, "spikes": [] }, { "name": "AdminAuthorize", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 12, "ok": 12, "ko": 0 }, "maxResponseTime": { "total": 12, "ok": 12, "ko": 0 }, "meanResponseTime": { "total": 12, "ok": 12, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 12, "ok": 12, "ko": 0 }, "percentiles2": { "total": 12, "ok": 12, "ko": 0 }, "percentiles3": { "total": 12, "ok": 12, "ko": 0 }, "percentiles4": { "total": 12, "ok": 12, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005461496450027307, "ok": 0.0005461496450027307, "ko": 0 }, "spikes": [] }, { "name": "Admin AccessToken stage", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 19, "ok": 19, "ko": 0 }, "maxResponseTime": { "total": 19, "ok": 19, "ko": 0 }, "meanResponseTime": { "total": 19, "ok": 19, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 19, "ok": 19, "ko": 0 }, "percentiles2": { "total": 19, "ok": 19, "ko": 0 }, "percentiles3": { "total": 19, "ok": 19, "ko": 0 }, "percentiles4": { "total": 19, "ok": 19, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 0, "percentage": 0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 0, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 0.0005461496450027307, "ok": 0.0005461496450027307, "ko": 0 }, "spikes": [] }, { "name": "ReadManagedUser", "numberOfRequests": { "total": 14483, "ok": 14483, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 61, "ok": 61, "ko": 0 }, "meanResponseTime": { "total": 6, "ok": 6, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 6, "ok": 6, "ko": 0 }, "percentiles2": { "total": 6, "ok": 6, "ko": 0 }, "percentiles3": { "total": 7, "ok": 7, "ko": 0 }, "percentiles4": { "total": 11, "ok": 11, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 14483, "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": 7.909885308574549, "ok": 7.909885308574549, "ko": 0 }, "spikes": [] }, { "name": "queryStartsWith", "numberOfRequests": { "total": 14580, "ok": 14580, "ko": 0 }, "minResponseTime": { "total": 85, "ok": 85, "ko": 0 }, "maxResponseTime": { "total": 383, "ok": 383, "ko": 0 }, "meanResponseTime": { "total": 233, "ok": 233, "ko": 0 }, "standardDeviation": { "total": 33, "ok": 33, "ko": 0 }, "percentiles1": { "total": 236, "ok": 236, "ko": 0 }, "percentiles2": { "total": 251, "ok": 251, "ko": 0 }, "percentiles3": { "total": 277, "ok": 277, "ko": 0 }, "percentiles4": { "total": 297, "ok": 297, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 14580, "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": 7.962861824139814, "ok": 7.962861824139814, "ko": 0 }, "spikes": [] }, { "name": "queryPartial", "numberOfRequests": { "total": 14576, "ok": 14576, "ko": 0 }, "minResponseTime": { "total": 163, "ok": 163, "ko": 0 }, "maxResponseTime": { "total": 358, "ok": 358, "ko": 0 }, "meanResponseTime": { "total": 228, "ok": 228, "ko": 0 }, "standardDeviation": { "total": 26, "ok": 26, "ko": 0 }, "percentiles1": { "total": 225, "ok": 225, "ko": 0 }, "percentiles2": { "total": 243, "ok": 243, "ko": 0 }, "percentiles3": { "total": 274, "ok": 274, "ko": 0 }, "percentiles4": { "total": 298, "ok": 298, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 14576, "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": 7.960677225559803, "ok": 7.960677225559803, "ko": 0 }, "spikes": [] }, { "name": "queryBySn", "numberOfRequests": { "total": 14435, "ok": 14435, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 71, "ok": 71, "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": 12, "ok": 12, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 14435, "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": 7.883670125614418, "ok": 7.883670125614418, "ko": 0 }, "spikes": [] }, { "name": "queryFull", "numberOfRequests": { "total": 14566, "ok": 14566, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 60, "ok": 60, "ko": 0 }, "meanResponseTime": { "total": 7, "ok": 7, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 7, "ok": 7, "ko": 0 }, "percentiles2": { "total": 7, "ok": 7, "ko": 0 }, "percentiles3": { "total": 9, "ok": 9, "ko": 0 }, "percentiles4": { "total": 11, "ok": 11, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 14566, "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": 7.955215729109776, "ok": 7.955215729109776, "ko": 0 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 40, "avg_response_time": 97, "max_response_time": 383, "min_response_time": 5, "num_requests": 72643, "num_requests_pass": 72643, "num_requests_percent_pass": 100.0 }, "options": "; duration:1800; simulation:com.forgerock.gatling.Loadtest; testCase:idm.MixedQuery; testMode:open; throughput:40", "stats": { "details": [ { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 24, "total": 24 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 24, "total": 24 }, "minResponseTime": { "ko": 0, "ok": 24, "total": 24 }, "name": "AdminRestLogin", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 24, "total": 24 }, "percentiles2": { "ko": 0, "ok": 24, "total": 24 }, "percentiles3": { "ko": 0, "ok": 24, "total": 24 }, "percentiles4": { "ko": 0, "ok": 24, "total": 24 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 12, "total": 12 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 12, "total": 12 }, "minResponseTime": { "ko": 0, "ok": 12, "total": 12 }, "name": "AdminAuthorize", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 12, "total": 12 }, "percentiles2": { "ko": 0, "ok": 12, "total": 12 }, "percentiles3": { "ko": 0, "ok": 12, "total": 12 }, "percentiles4": { "ko": 0, "ok": 12, "total": 12 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 1, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 19, "total": 19 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 19, "total": 19 }, "minResponseTime": { "ko": 0, "ok": 19, "total": 19 }, "name": "Admin AccessToken stage", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 19, "total": 19 }, "percentiles2": { "ko": 0, "ok": 19, "total": 19 }, "percentiles3": { "ko": 0, "ok": 19, "total": 19 }, "percentiles4": { "ko": 0, "ok": 19, "total": 19 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 0, "total": 0 } }, { "group1": { "count": 14483, "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": 61, "total": 61 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 7.909885308574549, "total": 7.909885308574549 }, "meanResponseTime": { "ko": 0, "ok": 6, "total": 6 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "ReadManagedUser", "numberOfRequests": { "ko": 0, "ok": 14483, "total": 14483 }, "percentiles1": { "ko": 0, "ok": 6, "total": 6 }, "percentiles2": { "ko": 0, "ok": 6, "total": 6 }, "percentiles3": { "ko": 0, "ok": 7, "total": 7 }, "percentiles4": { "ko": 0, "ok": 11, "total": 11 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } }, { "group1": { "count": 14580, "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": 383, "total": 383 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 7.962861824139814, "total": 7.962861824139814 }, "meanResponseTime": { "ko": 0, "ok": 233, "total": 233 }, "minResponseTime": { "ko": 0, "ok": 85, "total": 85 }, "name": "queryStartsWith", "numberOfRequests": { "ko": 0, "ok": 14580, "total": 14580 }, "percentiles1": { "ko": 0, "ok": 236, "total": 236 }, "percentiles2": { "ko": 0, "ok": 251, "total": 251 }, "percentiles3": { "ko": 0, "ok": 277, "total": 277 }, "percentiles4": { "ko": 0, "ok": 297, "total": 297 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 33, "total": 33 } }, { "group1": { "count": 14576, "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": 358, "total": 358 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 7.960677225559803, "total": 7.960677225559803 }, "meanResponseTime": { "ko": 0, "ok": 228, "total": 228 }, "minResponseTime": { "ko": 0, "ok": 163, "total": 163 }, "name": "queryPartial", "numberOfRequests": { "ko": 0, "ok": 14576, "total": 14576 }, "percentiles1": { "ko": 0, "ok": 225, "total": 225 }, "percentiles2": { "ko": 0, "ok": 243, "total": 243 }, "percentiles3": { "ko": 0, "ok": 274, "total": 274 }, "percentiles4": { "ko": 0, "ok": 298, "total": 298 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 26, "total": 26 } }, { "group1": { "count": 14435, "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": 71, "total": 71 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 7.883670125614418, "total": 7.883670125614418 }, "meanResponseTime": { "ko": 0, "ok": 7, "total": 7 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryBySn", "numberOfRequests": { "ko": 0, "ok": 14435, "total": 14435 }, "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": 12, "total": 12 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } }, { "group1": { "count": 14566, "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": 60, "total": 60 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 7.955215729109776, "total": 7.955215729109776 }, "meanResponseTime": { "ko": 0, "ok": 7, "total": 7 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryFull", "numberOfRequests": { "ko": 0, "ok": 14566, "total": 14566 }, "percentiles1": { "ko": 0, "ok": 7, "total": 7 }, "percentiles2": { "ko": 0, "ok": 7, "total": 7 }, "percentiles3": { "ko": 0, "ok": 9, "total": 9 }, "percentiles4": { "ko": 0, "ok": 11, "total": 11 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } } ], "global": { "group1": { "count": 72643, "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": 383, "total": 383 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 39.67394866193337, "total": 39.67394866193337 }, "meanResponseTime": { "ko": 0, "ok": 97, "total": 97 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "All Requests", "numberOfRequests": { "ko": 0, "ok": 72643, "total": 72643 }, "percentiles1": { "ko": 0, "ok": 8, "total": 8 }, "percentiles2": { "ko": 0, "ok": 223, "total": 223 }, "percentiles3": { "ko": 0, "ok": 261, "total": 261 }, "percentiles4": { "ko": 0, "ok": 286, "total": 286 }, "standardDeviation": { "ko": 0, "ok": 111, "total": 111 } } }, "target_hostname": "xlou.iam.xlou-cdm.engineeringpit.com", "tool_name": "gatling" } ________________________________________________________________________________ [2024-04-24 09:56:56] Reporting-mixedquery-40 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2024-04-24 09:56:56] Reporting-mixedquery-40 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/04_mixedquery-40/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2024-04-24 09:56:56] Reporting-mixedquery-40 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped