--Task-- name: Reporting-mixedquery-60 enabled: True class_name: GatlingResultTask source_name: overseer-0 source_namespace: >default< target_name: overseer-0 target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['Reporting-mixedquery-10', 'Reporting-mixedquery-20', 'Reporting-mixedquery-30', 'Reporting-mixedquery-40', 'Reporting-mixedquery-50'] preceding_task: None options: {'task_name': 'mixedquery-60'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 09:57:27] Reporting-mixedquery-60 pre : N/A ________________________________________________________________________________ source (overseer-0) is a component [2024-04-24 09:57:27] - INFO: Timeout for this Task has changed to 2h (7200 seconds) [2024-04-24 09:57:27] - 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-60/stdout.txt Pod stderr file : /results/orders/Reporting-mixedquery-60/stderr.txt Pod log : /results/orders/Reporting-mixedquery-60 ________________________________________________________________________________ [2024-04-24 09:57:27] Reporting-mixedquery-60 step1 : Write order for overseer to generate the report ________________________________________________________________________________ 2024-04-24 09:57:27,200 INFO 2024-04-24 09:57:27,200 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:27,200 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:27,532 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:27,532 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:27,532 DEBUG --- stdout --- 2024-04-24 09:57:27,532 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:27,532 DEBUG --- stderr --- 2024-04-24 09:57:27,532 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/14_Reporting-mixedquery-60/order.json { "name": "Reporting-mixedquery-60", "command": "/lodestar/clients/gatling-report-only-for-pyrock.sh mixedquery-60 --extra-run-jvm-options \"-Xmx12G\"", "out-dir": "/results/orders/Reporting-mixedquery-60" } ________________________________________________________________________________ [2024-04-24 09:57:27] Reporting-mixedquery-60 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2024-04-24 09:57:27,534 INFO 2024-04-24 09:57:27,534 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:57:27,534 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2024-04-24 09:57:27,842 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:57:27,843 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:27,843 DEBUG --- stdout --- 2024-04-24 09:57:27,843 DEBUG WAITING 2024-04-24 09:57:27,843 DEBUG --- stderr --- 2024-04-24 09:57:27,843 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 26 0 --:--:-- --:--:-- --:--:-- 26 ________________________________________________________________________________ [2024-04-24 09:57:27] Reporting-mixedquery-60 step3 : Send order to overseer ________________________________________________________________________________ 2024-04-24 09:57:27,844 INFO 2024-04-24 09:57:27,844 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:27,844 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:28,177 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:28,177 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:28,177 DEBUG --- stdout --- 2024-04-24 09:57:28,177 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:28,177 DEBUG --- stderr --- 2024-04-24 09:57:28,177 DEBUG 2024-04-24 09:57:28,178 INFO 2024-04-24 09:57:28,178 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/14_Reporting-mixedquery-60/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 09:57:28,178 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:57:29,586 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:29,586 DEBUG --- stdout --- 2024-04-24 09:57:29,586 DEBUG 2024-04-24 09:57:29,586 DEBUG --- stderr --- 2024-04-24 09:57:29,586 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2024-04-24 09:57:29] Reporting-mixedquery-60 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2024-04-24 09:57:34,591 INFO 2024-04-24 09:57:34,591 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:57:34,591 INFO [loop_until]: (max_time=7200, interval=5, expected_rc=[0] 2024-04-24 09:57:34,841 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2024-04-24 09:57:40,092 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2024-04-24 09:57:40,093 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:40,093 DEBUG --- stdout --- 2024-04-24 09:57:40,093 DEBUG WAITING 2024-04-24 09:57:40,093 DEBUG --- stderr --- 2024-04-24 09:57:40,093 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-24 09:57:40] Reporting-mixedquery-60 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2024-04-24 09:57:40,094 INFO 2024-04-24 09:57:40,094 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:40,094 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:40,431 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:40,431 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:40,432 DEBUG --- stdout --- 2024-04-24 09:57:40,432 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:40,432 DEBUG --- stderr --- 2024-04-24 09:57:40,432 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:57:40,432 INFO 2024-04-24 09:57:40,432 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:40,432 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:40,779 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:40,779 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:40,779 DEBUG --- stdout --- 2024-04-24 09:57:40,779 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:40,779 DEBUG --- stderr --- 2024-04-24 09:57:40,779 DEBUG 2024-04-24 09:57:40,780 INFO 2024-04-24 09:57:40,780 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-60.tar.gz" ]; then echo "/results/gatling/mixedquery-60.tar.gz file found"; exit 0; else echo "/results/gatling/mixedquery-60.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/mixedquery-60.tar.gz file found" 2024-04-24 09:57:40,780 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:57:41,641 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:57:41,641 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:41,641 DEBUG --- stdout --- 2024-04-24 09:57:41,641 DEBUG /results/gatling/mixedquery-60.tar.gz file found 2024-04-24 09:57:41,641 DEBUG --- stderr --- 2024-04-24 09:57:41,642 DEBUG 2024-04-24 09:57:41,642 INFO 2024-04-24 09:57:41,642 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:41,642 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:41,966 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:41,966 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:41,966 DEBUG --- stdout --- 2024-04-24 09:57:41,966 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:41,966 DEBUG --- stderr --- 2024-04-24 09:57:41,966 DEBUG 2024-04-24 09:57:41,967 INFO 2024-04-24 09:57:41,967 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-60.tar.gz 2024-04-24 09:57:42,824 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:57:42,825 DEBUG --- stdout --- 2024-04-24 09:57:42,825 DEBUG 348 /results/gatling/mixedquery-60.tar.gz 2024-04-24 09:57:42,825 DEBUG --- stderr --- 2024-04-24 09:57:42,825 DEBUG 2024-04-24 09:57:42,825 INFO - Download file (size 348k) 2024-04-24 09:57:42,826 INFO 2024-04-24 09:57:42,826 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:42,826 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:43,232 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:43,232 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:43,232 DEBUG --- stdout --- 2024-04-24 09:57:43,233 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:43,233 DEBUG --- stderr --- 2024-04-24 09:57:43,233 DEBUG source : /results/gatling/mixedquery-60.tar.gz @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-60.tar.gz 2024-04-24 09:57:43,233 INFO 2024-04-24 09:57:43,233 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:43,233 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:43,660 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:43,660 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:43,660 DEBUG --- stdout --- 2024-04-24 09:57:43,660 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:43,660 DEBUG --- stderr --- 2024-04-24 09:57:43,660 DEBUG 2024-04-24 09:57:43,661 INFO 2024-04-24 09:57:43,661 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/gatling/mixedquery-60.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-60.tar.gz 2024-04-24 09:57:43,661 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:57:44,718 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:44,719 DEBUG --- stdout --- 2024-04-24 09:57:44,719 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:57:44,719 DEBUG --- stderr --- 2024-04-24 09:57:44,719 DEBUG ________________________________________________________________________________ [2024-04-24 09:57:44] Reporting-mixedquery-60 step6 : Unzip the result ________________________________________________________________________________ 2024-04-24 09:57:44,720 INFO 2024-04-24 09:57:44,720 INFO [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/shared/mixedquery-60.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/gatling 2024-04-24 09:57:44,761 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:57:44,761 DEBUG --- stdout --- 2024-04-24 09:57:44,761 DEBUG 2024-04-24 09:57:44,761 DEBUG --- stderr --- 2024-04-24 09:57:44,761 DEBUG 2024-04-24 09:57:44,761 INFO ________________________________________________________________________________ [2024-04-24 09:57:44] Reporting-mixedquery-60 step7 : Print rc order ________________________________________________________________________________ 2024-04-24 09:57:44,763 INFO 2024-04-24 09:57:44,763 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:44,763 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:45,126 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:45,126 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:45,126 DEBUG --- stdout --- 2024-04-24 09:57:45,126 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:45,126 DEBUG --- stderr --- 2024-04-24 09:57:45,126 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:57:45,127 INFO 2024-04-24 09:57:45,127 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:45,127 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:45,526 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:45,527 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:45,527 DEBUG --- stdout --- 2024-04-24 09:57:45,527 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:45,527 DEBUG --- stderr --- 2024-04-24 09:57:45,527 DEBUG 2024-04-24 09:57:45,527 INFO 2024-04-24 09:57:45,527 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-60/rc.txt" ]; then echo "/results/orders/Reporting-mixedquery-60/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-60/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-60/rc.txt file found" 2024-04-24 09:57:45,528 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:57:46,410 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:57:46,410 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:46,410 DEBUG --- stdout --- 2024-04-24 09:57:46,410 DEBUG /results/orders/Reporting-mixedquery-60/rc.txt file found 2024-04-24 09:57:46,410 DEBUG --- stderr --- 2024-04-24 09:57:46,410 DEBUG 2024-04-24 09:57:46,411 INFO 2024-04-24 09:57:46,411 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:46,411 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:46,748 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:46,748 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:46,748 DEBUG --- stdout --- 2024-04-24 09:57:46,748 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:46,748 DEBUG --- stderr --- 2024-04-24 09:57:46,749 DEBUG 2024-04-24 09:57:46,749 INFO 2024-04-24 09:57:46,749 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-60/rc.txt 2024-04-24 09:57:47,627 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:57:47,627 DEBUG --- stdout --- 2024-04-24 09:57:47,627 DEBUG 4 /results/orders/Reporting-mixedquery-60/rc.txt 2024-04-24 09:57:47,627 DEBUG --- stderr --- 2024-04-24 09:57:47,627 DEBUG 2024-04-24 09:57:47,627 INFO - Download file (size 4k) 2024-04-24 09:57:47,628 INFO 2024-04-24 09:57:47,628 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:47,628 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:47,959 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:47,959 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:47,959 DEBUG --- stdout --- 2024-04-24 09:57:47,959 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:47,959 DEBUG --- stderr --- 2024-04-24 09:57:47,959 DEBUG source : /results/orders/Reporting-mixedquery-60/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/14_Reporting-mixedquery-60/rc.txt 2024-04-24 09:57:47,960 INFO 2024-04-24 09:57:47,960 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:47,960 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:48,282 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:48,282 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:48,282 DEBUG --- stdout --- 2024-04-24 09:57:48,282 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:48,282 DEBUG --- stderr --- 2024-04-24 09:57:48,282 DEBUG 2024-04-24 09:57:48,283 INFO 2024-04-24 09:57:48,283 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-60/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/14_Reporting-mixedquery-60/rc.txt 2024-04-24 09:57:48,283 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:57:49,095 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:49,095 DEBUG --- stdout --- 2024-04-24 09:57:49,095 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:57:49,095 DEBUG --- stderr --- 2024-04-24 09:57:49,095 DEBUG 2024-04-24 09:57:49,096 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/14_Reporting-mixedquery-60/rc.txt is small (1 lines), printing all lines: 2024-04-24 09:57:49,096 DEBUG ----- output ----- 2024-04-24 09:57:49,096 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 09:57:49,096 INFO ________________________________________________________________________________ [2024-04-24 09:57:49] Reporting-mixedquery-60 step8 : Check rc log ________________________________________________________________________________ PASS: no error found in rc log ________________________________________________________________________________ [2024-04-24 09:57:49] Reporting-mixedquery-60 step9 : Print stdout order ________________________________________________________________________________ 2024-04-24 09:57:49,098 INFO 2024-04-24 09:57:49,098 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:49,098 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:49,436 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:49,436 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:49,436 DEBUG --- stdout --- 2024-04-24 09:57:49,436 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:49,436 DEBUG --- stderr --- 2024-04-24 09:57:49,436 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:57:49,437 INFO 2024-04-24 09:57:49,437 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:49,437 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:49,826 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:49,829 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:49,829 DEBUG --- stdout --- 2024-04-24 09:57:49,829 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:49,829 DEBUG --- stderr --- 2024-04-24 09:57:49,829 DEBUG 2024-04-24 09:57:49,830 INFO 2024-04-24 09:57:49,830 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-60/stdout.txt" ]; then echo "/results/orders/Reporting-mixedquery-60/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-60/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-60/stdout.txt file found" 2024-04-24 09:57:49,830 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:57:50,768 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:57:50,768 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:50,768 DEBUG --- stdout --- 2024-04-24 09:57:50,769 DEBUG /results/orders/Reporting-mixedquery-60/stdout.txt file found 2024-04-24 09:57:50,769 DEBUG --- stderr --- 2024-04-24 09:57:50,769 DEBUG 2024-04-24 09:57:50,769 INFO 2024-04-24 09:57:50,769 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:50,769 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:51,151 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:51,151 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:51,151 DEBUG --- stdout --- 2024-04-24 09:57:51,152 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:51,152 DEBUG --- stderr --- 2024-04-24 09:57:51,152 DEBUG 2024-04-24 09:57:51,152 INFO 2024-04-24 09:57:51,152 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-60/stdout.txt 2024-04-24 09:57:52,084 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:57:52,084 DEBUG --- stdout --- 2024-04-24 09:57:52,084 DEBUG 4 /results/orders/Reporting-mixedquery-60/stdout.txt 2024-04-24 09:57:52,085 DEBUG --- stderr --- 2024-04-24 09:57:52,085 DEBUG 2024-04-24 09:57:52,085 INFO - Download file (size 4k) 2024-04-24 09:57:52,085 INFO 2024-04-24 09:57:52,085 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:52,085 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:52,460 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:52,461 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:52,461 DEBUG --- stdout --- 2024-04-24 09:57:52,461 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:52,461 DEBUG --- stderr --- 2024-04-24 09:57:52,461 DEBUG source : /results/orders/Reporting-mixedquery-60/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/14_Reporting-mixedquery-60/stdout.txt 2024-04-24 09:57:52,461 INFO 2024-04-24 09:57:52,461 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:52,462 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:52,794 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:52,795 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:52,795 DEBUG --- stdout --- 2024-04-24 09:57:52,795 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:52,795 DEBUG --- stderr --- 2024-04-24 09:57:52,795 DEBUG 2024-04-24 09:57:52,795 INFO 2024-04-24 09:57:52,795 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-60/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/14_Reporting-mixedquery-60/stdout.txt 2024-04-24 09:57:52,795 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:57:53,644 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:53,644 DEBUG --- stdout --- 2024-04-24 09:57:53,644 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:57:53,644 DEBUG --- stderr --- 2024-04-24 09:57:53,644 DEBUG 2024-04-24 09:57:53,645 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/14_Reporting-mixedquery-60/stdout.txt is small (81 lines), printing all lines: 2024-04-24 09:57:53,645 DEBUG ----- output ----- 2024-04-24 09:57:53,645 DEBUG [cmd] /lodestar/clients/gatling/bin/gatling.sh --reports-only /results/gatling/mixedquery-60/loadtest-20240424081639946 --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 108405 (OK=108405 KO=0 ) > min response time 5 (OK=5 KO=- ) > max response time 414 (OK=414 KO=- ) > mean response time 102 (OK=102 KO=- ) > std deviation 118 (OK=118 KO=- ) > response time 50th percentile 8 (OK=8 KO=- ) > response time 75th percentile 234 (OK=234 KO=- ) > response time 95th percentile 278 (OK=278 KO=- ) > response time 99th percentile 307 (OK=307 KO=- ) > mean requests/sec 59.205 (OK=59.205 KO=- ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 108405 (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-60/loadtest-20240424081639946/index.html ---------------------- [24/Apr/2024 10:57:38] Create tar file /results/gatling/mixedquery-60.tar.gz ---------------------- SIMULATION_PATH=/results/gatling/mixedquery-60/loadtest-20240424081639946 RESULT_FOLDER=mixedquery-60 RESULT_PATH=/results/gatling/mixedquery-60 PWD=/results/gatling/mixedquery-60/loadtest-20240424081639946 [cmd] tar -czvf /results/gatling/mixedquery-60.tar.gz --exclude=simulation.log * group_retrievevariabl-5a49b.html group_storevariables-5102b.html index.html js/ js/global_stats.json js/menu.js js/all_sessions.js js/unpack.js js/highcharts-more.js js/jquery-3.5.1.min.js js/bootstrap.min.js js/stats.js js/highstock.js js/ellipsis.js js/assertions.xml js/stats.json js/assertions.json js/gatling.js js/theme.js req_admin-accesstok-f0286.html req_adminauthorize-f6e0c.html req_adminrestlogin-b480c.html req_querybysn-65c9b.html req_queryfull-e5d3d.html req_querypartial-f2acd.html req_querystartswith-1e9cd.html req_readmanageduser-f3f1e.html style/ style/logo-enterprise.svg style/arrow_down_black.png style/arrow_right_black.png style/sorted-down.png style/arrow_down.png style/stat-l-temps.png style/little_arrow_right.png style/favicon.ico style/sortable.png style/arrow_right.png style/logo.svg style/stat-fleche-bas.png style/sorted-up.png style/bootstrap.min.css style/style.css style/stat-l-roue.png 2024-04-24 09:57:53,645 INFO ________________________________________________________________________________ [2024-04-24 09:57:53] Reporting-mixedquery-60 step10 : Check stdout log ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2024-04-24 09:57:53] Reporting-mixedquery-60 step11 : Print stderr order ________________________________________________________________________________ 2024-04-24 09:57:53,647 INFO 2024-04-24 09:57:53,647 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:53,647 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:53,991 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:53,992 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:53,992 DEBUG --- stdout --- 2024-04-24 09:57:53,992 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:53,992 DEBUG --- stderr --- 2024-04-24 09:57:53,992 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:57:53,992 INFO 2024-04-24 09:57:53,992 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:53,993 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:54,323 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:54,323 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:54,324 DEBUG --- stdout --- 2024-04-24 09:57:54,324 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:54,324 DEBUG --- stderr --- 2024-04-24 09:57:54,324 DEBUG 2024-04-24 09:57:54,324 INFO 2024-04-24 09:57:54,324 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-60/stderr.txt" ]; then echo "/results/orders/Reporting-mixedquery-60/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-mixedquery-60/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-mixedquery-60/stderr.txt file found" 2024-04-24 09:57:54,324 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:57:55,179 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:57:55,181 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:55,182 DEBUG --- stdout --- 2024-04-24 09:57:55,182 DEBUG /results/orders/Reporting-mixedquery-60/stderr.txt file found 2024-04-24 09:57:55,182 DEBUG --- stderr --- 2024-04-24 09:57:55,182 DEBUG 2024-04-24 09:57:55,183 INFO 2024-04-24 09:57:55,183 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:55,183 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:55,546 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:55,547 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:55,547 DEBUG --- stdout --- 2024-04-24 09:57:55,547 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:55,547 DEBUG --- stderr --- 2024-04-24 09:57:55,547 DEBUG 2024-04-24 09:57:55,547 INFO 2024-04-24 09:57:55,547 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-60/stderr.txt 2024-04-24 09:57:56,407 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:57:56,408 DEBUG --- stdout --- 2024-04-24 09:57:56,408 DEBUG 0 /results/orders/Reporting-mixedquery-60/stderr.txt 2024-04-24 09:57:56,408 DEBUG --- stderr --- 2024-04-24 09:57:56,408 DEBUG 2024-04-24 09:57:56,408 INFO - Download file (size 0k) 2024-04-24 09:57:56,408 INFO 2024-04-24 09:57:56,409 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:56,409 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:56,758 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:56,759 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:56,759 DEBUG --- stdout --- 2024-04-24 09:57:56,759 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:56,759 DEBUG --- stderr --- 2024-04-24 09:57:56,759 DEBUG source : /results/orders/Reporting-mixedquery-60/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/14_Reporting-mixedquery-60/stderr.txt 2024-04-24 09:57:56,759 INFO 2024-04-24 09:57:56,759 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:57:56,760 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:57:57,120 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:57:57,120 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:57,120 DEBUG --- stdout --- 2024-04-24 09:57:57,121 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:57:57,121 DEBUG --- stderr --- 2024-04-24 09:57:57,121 DEBUG 2024-04-24 09:57:57,121 INFO 2024-04-24 09:57:57,121 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-60/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/14_Reporting-mixedquery-60/stderr.txt 2024-04-24 09:57:57,121 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:57:57,943 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:57:57,944 DEBUG --- stdout --- 2024-04-24 09:57:57,944 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:57:57,944 DEBUG --- stderr --- 2024-04-24 09:57:57,944 DEBUG 2024-04-24 09:57:57,944 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/08_Verification/14_Reporting-mixedquery-60/stderr.txt is small (0 lines), printing all lines: 2024-04-24 09:57:57,944 DEBUG ----- output ----- 2024-04-24 09:57:57,944 DEBUG 2024-04-24 09:57:57,944 INFO ________________________________________________________________________________ [2024-04-24 09:57:57] Reporting-mixedquery-60 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-24 09:57:57] Reporting-mixedquery-60 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 queryPartial checking simulation request queryStartsWith checking simulation request ReadManagedUser checking simulation request queryBySn checking simulation request queryFull no spikes found ________________________________________________________________________________ [2024-04-24 09:57:57] Reporting-mixedquery-60 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 108405, "ok": 108405, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 414, "ok": 414, "ko": 0 }, "meanResponseTime": { "total": 102, "ok": 102, "ko": 0 }, "standardDeviation": { "total": 118, "ok": 118, "ko": 0 }, "percentiles1": { "total": 8, "ok": 8, "ko": 0 }, "percentiles2": { "total": 234, "ok": 234, "ko": 0 }, "percentiles3": { "total": 278, "ok": 278, "ko": 0 }, "percentiles4": { "total": 307, "ok": 307, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 108405, "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": 59.205352266521025, "ok": 59.205352266521025, "ko": 0 } }, "details": [ { "name": "AdminRestLogin", "numberOfRequests": { "total": 1, "ok": 1, "ko": 0 }, "minResponseTime": { "total": 26, "ok": 26, "ko": 0 }, "maxResponseTime": { "total": 26, "ok": 26, "ko": 0 }, "meanResponseTime": { "total": 26, "ok": 26, "ko": 0 }, "standardDeviation": { "total": 0, "ok": 0, "ko": 0 }, "percentiles1": { "total": 26, "ok": 26, "ko": 0 }, "percentiles2": { "total": 26, "ok": 26, "ko": 0 }, "percentiles3": { "total": 26, "ok": 26, "ko": 0 }, "percentiles4": { "total": 26, "ok": 26, "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": "queryPartial", "numberOfRequests": { "total": 21848, "ok": 21848, "ko": 0 }, "minResponseTime": { "total": 163, "ok": 163, "ko": 0 }, "maxResponseTime": { "total": 412, "ok": 412, "ko": 0 }, "meanResponseTime": { "total": 245, "ok": 245, "ko": 0 }, "standardDeviation": { "total": 32, "ok": 32, "ko": 0 }, "percentiles1": { "total": 242, "ok": 242, "ko": 0 }, "percentiles2": { "total": 265, "ok": 265, "ko": 0 }, "percentiles3": { "total": 301, "ok": 301, "ko": 0 }, "percentiles4": { "total": 332, "ok": 332, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 21848, "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": 11.932277444019661, "ok": 11.932277444019661, "ko": 0 }, "spikes": [] }, { "name": "queryStartsWith", "numberOfRequests": { "total": 21828, "ok": 21828, "ko": 0 }, "minResponseTime": { "total": 83, "ok": 83, "ko": 0 }, "maxResponseTime": { "total": 414, "ok": 414, "ko": 0 }, "meanResponseTime": { "total": 241, "ok": 241, "ko": 0 }, "standardDeviation": { "total": 34, "ok": 34, "ko": 0 }, "percentiles1": { "total": 243, "ok": 243, "ko": 0 }, "percentiles2": { "total": 260, "ok": 260, "ko": 0 }, "percentiles3": { "total": 288, "ok": 288, "ko": 0 }, "percentiles4": { "total": 310, "ok": 311, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 21828, "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": 11.921354451119607, "ok": 11.921354451119607, "ko": 0 }, "spikes": [] }, { "name": "ReadManagedUser", "numberOfRequests": { "total": 21309, "ok": 21309, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 63, "ok": 63, "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": 8, "ok": 8, "ko": 0 }, "percentiles4": { "total": 13, "ok": 14, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 21309, "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": 11.63790278536319, "ok": 11.63790278536319, "ko": 0 }, "spikes": [] }, { "name": "queryBySn", "numberOfRequests": { "total": 21706, "ok": 21706, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 66, "ok": 66, "ko": 0 }, "meanResponseTime": { "total": 7, "ok": 7, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 7, "ok": 7, "ko": 0 }, "percentiles2": { "total": 8, "ok": 8, "ko": 0 }, "percentiles3": { "total": 9, "ok": 9, "ko": 0 }, "percentiles4": { "total": 15, "ok": 15, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 21706, "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": 11.854724194429274, "ok": 11.854724194429274, "ko": 0 }, "spikes": [] }, { "name": "queryFull", "numberOfRequests": { "total": 21711, "ok": 21711, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 59, "ok": 59, "ko": 0 }, "meanResponseTime": { "total": 7, "ok": 7, "ko": 0 }, "standardDeviation": { "total": 2, "ok": 2, "ko": 0 }, "percentiles1": { "total": 7, "ok": 7, "ko": 0 }, "percentiles2": { "total": 7, "ok": 7, "ko": 0 }, "percentiles3": { "total": 9, "ok": 9, "ko": 0 }, "percentiles4": { "total": 14, "ok": 14, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 21711, "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": 11.857454942654288, "ok": 11.857454942654288, "ko": 0 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 59, "avg_response_time": 102, "max_response_time": 414, "min_response_time": 5, "num_requests": 108405, "num_requests_pass": 108405, "num_requests_percent_pass": 100.0 }, "options": "; duration:1800; simulation:com.forgerock.gatling.Loadtest; testCase:idm.MixedQuery; testMode:open; throughput:60", "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": 26, "total": 26 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 0.0005461496450027307, "total": 0.0005461496450027307 }, "meanResponseTime": { "ko": 0, "ok": 26, "total": 26 }, "minResponseTime": { "ko": 0, "ok": 26, "total": 26 }, "name": "AdminRestLogin", "numberOfRequests": { "ko": 0, "ok": 1, "total": 1 }, "percentiles1": { "ko": 0, "ok": 26, "total": 26 }, "percentiles2": { "ko": 0, "ok": 26, "total": 26 }, "percentiles3": { "ko": 0, "ok": 26, "total": 26 }, "percentiles4": { "ko": 0, "ok": 26, "total": 26 }, "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": 21848, "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": 412, "total": 412 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 11.932277444019661, "total": 11.932277444019661 }, "meanResponseTime": { "ko": 0, "ok": 245, "total": 245 }, "minResponseTime": { "ko": 0, "ok": 163, "total": 163 }, "name": "queryPartial", "numberOfRequests": { "ko": 0, "ok": 21848, "total": 21848 }, "percentiles1": { "ko": 0, "ok": 242, "total": 242 }, "percentiles2": { "ko": 0, "ok": 265, "total": 265 }, "percentiles3": { "ko": 0, "ok": 301, "total": 301 }, "percentiles4": { "ko": 0, "ok": 332, "total": 332 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 32, "total": 32 } }, { "group1": { "count": 21828, "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": 414, "total": 414 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 11.921354451119607, "total": 11.921354451119607 }, "meanResponseTime": { "ko": 0, "ok": 241, "total": 241 }, "minResponseTime": { "ko": 0, "ok": 83, "total": 83 }, "name": "queryStartsWith", "numberOfRequests": { "ko": 0, "ok": 21828, "total": 21828 }, "percentiles1": { "ko": 0, "ok": 243, "total": 243 }, "percentiles2": { "ko": 0, "ok": 260, "total": 260 }, "percentiles3": { "ko": 0, "ok": 288, "total": 288 }, "percentiles4": { "ko": 0, "ok": 311, "total": 310 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 34, "total": 34 } }, { "group1": { "count": 21309, "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": 11.63790278536319, "total": 11.63790278536319 }, "meanResponseTime": { "ko": 0, "ok": 6, "total": 6 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "ReadManagedUser", "numberOfRequests": { "ko": 0, "ok": 21309, "total": 21309 }, "percentiles1": { "ko": 0, "ok": 6, "total": 6 }, "percentiles2": { "ko": 0, "ok": 6, "total": 6 }, "percentiles3": { "ko": 0, "ok": 8, "total": 8 }, "percentiles4": { "ko": 0, "ok": 14, "total": 13 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } }, { "group1": { "count": 21706, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 66, "total": 66 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 11.854724194429274, "total": 11.854724194429274 }, "meanResponseTime": { "ko": 0, "ok": 7, "total": 7 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryBySn", "numberOfRequests": { "ko": 0, "ok": 21706, "total": 21706 }, "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": 15, "total": 15 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } }, { "group1": { "count": 21711, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 0, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 0, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 59, "total": 59 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 11.857454942654288, "total": 11.857454942654288 }, "meanResponseTime": { "ko": 0, "ok": 7, "total": 7 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "queryFull", "numberOfRequests": { "ko": 0, "ok": 21711, "total": 21711 }, "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": 14, "total": 14 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 2, "total": 2 } } ], "global": { "group1": { "count": 108405, "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": 414, "total": 414 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 59.205352266521025, "total": 59.205352266521025 }, "meanResponseTime": { "ko": 0, "ok": 102, "total": 102 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "All Requests", "numberOfRequests": { "ko": 0, "ok": 108405, "total": 108405 }, "percentiles1": { "ko": 0, "ok": 8, "total": 8 }, "percentiles2": { "ko": 0, "ok": 234, "total": 234 }, "percentiles3": { "ko": 0, "ok": 278, "total": 278 }, "percentiles4": { "ko": 0, "ok": 307, "total": 307 }, "standardDeviation": { "ko": 0, "ok": 118, "total": 118 } } }, "target_hostname": "xlou.iam.xlou-cdm.engineeringpit.com", "tool_name": "gatling" } ________________________________________________________________________________ [2024-04-24 09:57:57] Reporting-mixedquery-60 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2024-04-24 09:57:57] Reporting-mixedquery-60 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/06_mixedquery-60/stdout.txt Gatling stdout.txt found, but is small - leave it intact. ________________________________________________________________________________ [2024-04-24 09:57:57] Reporting-mixedquery-60 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped