--Task-- name: Reporting-login-tree-50 enabled: True class_name: GatlingResultTask source_name: overseer-0 source_namespace: >default< target_name: overseer-0 target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['Reporting-login-tree-10'] preceding_task: None options: {'task_name': 'login-tree-50'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-13 06:10:08] Reporting-login-tree-50 pre : N/A ________________________________________________________________________________ source (overseer-0) is a component 2024-06-13 06:10:08 - INFO: Timeout for this Task has changed to 3h (10800 seconds) 2024-06-13 06:10:08 - 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-login-tree-50/stdout.txt Pod stderr file : /results/orders/Reporting-login-tree-50/stderr.txt Pod log : /results/orders/Reporting-login-tree-50 ________________________________________________________________________________ [2024-06-13 06:10:08] Reporting-login-tree-50 step1 : Write order for overseer to generate the report ________________________________________________________________________________ 2024-06-13 06:10:08,024 INFO 2024-06-13 06:10:08,025 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-06-13 06:10:08,025 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:08,341 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:08,341 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:08,341 DEBUG --- stdout --- 2024-06-13 06:10:08,341 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:08,341 DEBUG --- stderr --- 2024-06-13 06:10:08,341 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/10_Reporting-login-tree-50/order.json { "name": "Reporting-login-tree-50", "command": "/lodestar/clients/gatling-report-only-for-pyrock.sh login-tree-50 --extra-run-jvm-options \"-Xmx4G\"", "out-dir": "/results/orders/Reporting-login-tree-50" } ________________________________________________________________________________ [2024-06-13 06:10:08] Reporting-login-tree-50 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ 2024-06-13 06:10:08,343 INFO 2024-06-13 06:10:08,343 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-13 06:10:08,343 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0] 2024-06-13 06:10:08,622 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 06:10:08,622 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:08,622 DEBUG --- stdout --- 2024-06-13 06:10:08,623 DEBUG WAITING 2024-06-13 06:10:08,623 DEBUG --- stderr --- 2024-06-13 06:10:08,623 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 27 0 --:--:-- --:--:-- --:--:-- 27 ________________________________________________________________________________ [2024-06-13 06:10:08] Reporting-login-tree-50 step3 : Send order to overseer ________________________________________________________________________________ 2024-06-13 06:10:08,624 INFO 2024-06-13 06:10:08,624 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-06-13 06:10:08,624 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:08,933 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:08,933 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:08,933 DEBUG --- stdout --- 2024-06-13 06:10:08,934 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:08,934 DEBUG --- stderr --- 2024-06-13 06:10:08,934 DEBUG 2024-06-13 06:10:08,934 INFO 2024-06-13 06:10:08,934 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/10_Reporting-login-tree-50/order.json overseer-0-6c7d65494f-sln6z:/results/orders/order.json 2024-06-13 06:10:08,934 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 06:10:10,288 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:10,288 DEBUG --- stdout --- 2024-06-13 06:10:10,288 DEBUG 2024-06-13 06:10:10,288 DEBUG --- stderr --- 2024-06-13 06:10:10,288 DEBUG Order has been successfully forwarded to overseer ________________________________________________________________________________ [2024-06-13 06:10:10] Reporting-login-tree-50 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. 2024-06-13 06:10:15,294 INFO 2024-06-13 06:10:15,294 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-13 06:10:15,296 INFO [loop_until]: (max_time=10800, interval=5, expected_rc=[0] 2024-06-13 06:10:15,539 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry 2024-06-13 06:10:20,851 INFO [loop_until]: Function succeeded after 5s (rc=0) - expected pattern found 2024-06-13 06:10:20,852 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:20,852 DEBUG --- stdout --- 2024-06-13 06:10:20,852 DEBUG WAITING 2024-06-13 06:10:20,852 DEBUG --- stderr --- 2024-06-13 06:10:20,852 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 24 0 --:--:-- --:--:-- --:--:-- 24 ________________________________________________________________________________ [2024-06-13 06:10:20] Reporting-login-tree-50 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ 2024-06-13 06:10:20,853 INFO 2024-06-13 06:10:20,853 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-06-13 06:10:20,853 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:21,204 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:21,204 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:21,204 DEBUG --- stdout --- 2024-06-13 06:10:21,204 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:21,204 DEBUG --- stderr --- 2024-06-13 06:10:21,204 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 06:10:21,205 INFO 2024-06-13 06:10:21,205 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-06-13 06:10:21,205 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:21,504 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:21,504 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:21,504 DEBUG --- stdout --- 2024-06-13 06:10:21,504 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:21,504 DEBUG --- stderr --- 2024-06-13 06:10:21,504 DEBUG 2024-06-13 06:10:21,505 INFO 2024-06-13 06:10:21,505 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/gatling/login-tree-50.tar.gz" ]; then echo "/results/gatling/login-tree-50.tar.gz file found"; exit 0; else echo "/results/gatling/login-tree-50.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/login-tree-50.tar.gz file found" 2024-06-13 06:10:21,505 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 06:10:22,340 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 06:10:22,340 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:22,340 DEBUG --- stdout --- 2024-06-13 06:10:22,340 DEBUG /results/gatling/login-tree-50.tar.gz file found 2024-06-13 06:10:22,340 DEBUG --- stderr --- 2024-06-13 06:10:22,340 DEBUG 2024-06-13 06:10:22,341 INFO 2024-06-13 06:10:22,341 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-06-13 06:10:22,341 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:22,640 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:22,640 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:22,640 DEBUG --- stdout --- 2024-06-13 06:10:22,640 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:22,640 DEBUG --- stderr --- 2024-06-13 06:10:22,641 DEBUG 2024-06-13 06:10:22,641 INFO 2024-06-13 06:10:22,641 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/gatling/login-tree-50.tar.gz 2024-06-13 06:10:23,451 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 06:10:23,451 DEBUG --- stdout --- 2024-06-13 06:10:23,451 DEBUG 320 /results/gatling/login-tree-50.tar.gz 2024-06-13 06:10:23,451 DEBUG --- stderr --- 2024-06-13 06:10:23,451 DEBUG 2024-06-13 06:10:23,451 INFO - Download file (size 320k) 2024-06-13 06:10:23,452 INFO 2024-06-13 06:10:23,452 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-06-13 06:10:23,452 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:23,770 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:23,771 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:23,771 DEBUG --- stdout --- 2024-06-13 06:10:23,771 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:23,771 DEBUG --- stderr --- 2024-06-13 06:10:23,771 DEBUG source : /results/gatling/login-tree-50.tar.gz @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/shared/login-tree-50.tar.gz 2024-06-13 06:10:23,771 INFO 2024-06-13 06:10:23,771 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-06-13 06:10:23,771 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:24,079 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:24,079 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:24,079 DEBUG --- stdout --- 2024-06-13 06:10:24,079 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:24,079 DEBUG --- stderr --- 2024-06-13 06:10:24,079 DEBUG 2024-06-13 06:10:24,080 INFO 2024-06-13 06:10:24,080 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/gatling/login-tree-50.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/shared/login-tree-50.tar.gz 2024-06-13 06:10:24,080 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 06:10:25,080 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:25,080 DEBUG --- stdout --- 2024-06-13 06:10:25,081 DEBUG tar: Removing leading `/' from member names 2024-06-13 06:10:25,081 DEBUG --- stderr --- 2024-06-13 06:10:25,081 DEBUG ________________________________________________________________________________ [2024-06-13 06:10:25] Reporting-login-tree-50 step6 : Unzip the result ________________________________________________________________________________ 2024-06-13 06:10:25,082 INFO 2024-06-13 06:10:25,082 INFO [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/shared/login-tree-50.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/gatling 2024-06-13 06:10:25,107 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 06:10:25,107 DEBUG --- stdout --- 2024-06-13 06:10:25,107 DEBUG 2024-06-13 06:10:25,108 DEBUG --- stderr --- 2024-06-13 06:10:25,108 DEBUG 2024-06-13 06:10:25,108 INFO ________________________________________________________________________________ [2024-06-13 06:10:25] Reporting-login-tree-50 step7 : Print rc order ________________________________________________________________________________ 2024-06-13 06:10:25,109 INFO 2024-06-13 06:10:25,109 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-06-13 06:10:25,109 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:25,416 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:25,416 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:25,417 DEBUG --- stdout --- 2024-06-13 06:10:25,417 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:25,417 DEBUG --- stderr --- 2024-06-13 06:10:25,417 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 06:10:25,417 INFO 2024-06-13 06:10:25,417 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-06-13 06:10:25,417 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:25,723 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:25,724 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:25,724 DEBUG --- stdout --- 2024-06-13 06:10:25,724 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:25,724 DEBUG --- stderr --- 2024-06-13 06:10:25,724 DEBUG 2024-06-13 06:10:25,724 INFO 2024-06-13 06:10:25,724 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/Reporting-login-tree-50/rc.txt" ]; then echo "/results/orders/Reporting-login-tree-50/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-login-tree-50/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-login-tree-50/rc.txt file found" 2024-06-13 06:10:25,724 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 06:10:26,515 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 06:10:26,515 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:26,515 DEBUG --- stdout --- 2024-06-13 06:10:26,515 DEBUG /results/orders/Reporting-login-tree-50/rc.txt file found 2024-06-13 06:10:26,515 DEBUG --- stderr --- 2024-06-13 06:10:26,515 DEBUG 2024-06-13 06:10:26,515 INFO 2024-06-13 06:10:26,515 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-06-13 06:10:26,515 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:26,809 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:26,809 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:26,809 DEBUG --- stdout --- 2024-06-13 06:10:26,809 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:26,810 DEBUG --- stderr --- 2024-06-13 06:10:26,810 DEBUG 2024-06-13 06:10:26,810 INFO 2024-06-13 06:10:26,810 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/Reporting-login-tree-50/rc.txt 2024-06-13 06:10:27,605 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 06:10:27,606 DEBUG --- stdout --- 2024-06-13 06:10:27,606 DEBUG 4 /results/orders/Reporting-login-tree-50/rc.txt 2024-06-13 06:10:27,606 DEBUG --- stderr --- 2024-06-13 06:10:27,606 DEBUG 2024-06-13 06:10:27,606 INFO - Download file (size 4k) 2024-06-13 06:10:27,606 INFO 2024-06-13 06:10:27,607 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-06-13 06:10:27,607 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:27,920 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:27,921 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:27,921 DEBUG --- stdout --- 2024-06-13 06:10:27,921 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:27,921 DEBUG --- stderr --- 2024-06-13 06:10:27,921 DEBUG source : /results/orders/Reporting-login-tree-50/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/10_Reporting-login-tree-50/rc.txt 2024-06-13 06:10:27,921 INFO 2024-06-13 06:10:27,921 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-06-13 06:10:27,921 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:28,229 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:28,229 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:28,229 DEBUG --- stdout --- 2024-06-13 06:10:28,229 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:28,229 DEBUG --- stderr --- 2024-06-13 06:10:28,229 DEBUG 2024-06-13 06:10:28,230 INFO 2024-06-13 06:10:28,230 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/Reporting-login-tree-50/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/10_Reporting-login-tree-50/rc.txt 2024-06-13 06:10:28,230 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 06:10:29,004 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:29,005 DEBUG --- stdout --- 2024-06-13 06:10:29,005 DEBUG tar: Removing leading `/' from member names 2024-06-13 06:10:29,005 DEBUG --- stderr --- 2024-06-13 06:10:29,005 DEBUG 2024-06-13 06:10:29,005 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/10_Reporting-login-tree-50/rc.txt is small (1 lines), printing all lines: 2024-06-13 06:10:29,005 DEBUG ----- output ----- 2024-06-13 06:10:29,005 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-13 06:10:29,005 INFO ________________________________________________________________________________ [2024-06-13 06:10:29] Reporting-login-tree-50 step8 : Check rc log ________________________________________________________________________________ PASS: no error found in rc log ________________________________________________________________________________ [2024-06-13 06:10:29] Reporting-login-tree-50 step9 : Print stdout order ________________________________________________________________________________ 2024-06-13 06:10:29,006 INFO 2024-06-13 06:10:29,006 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-06-13 06:10:29,006 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:29,302 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:29,302 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:29,302 DEBUG --- stdout --- 2024-06-13 06:10:29,302 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:29,302 DEBUG --- stderr --- 2024-06-13 06:10:29,302 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 06:10:29,303 INFO 2024-06-13 06:10:29,303 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-06-13 06:10:29,303 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:29,614 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:29,615 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:29,615 DEBUG --- stdout --- 2024-06-13 06:10:29,615 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:29,615 DEBUG --- stderr --- 2024-06-13 06:10:29,615 DEBUG 2024-06-13 06:10:29,616 INFO 2024-06-13 06:10:29,616 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/Reporting-login-tree-50/stdout.txt" ]; then echo "/results/orders/Reporting-login-tree-50/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-login-tree-50/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-login-tree-50/stdout.txt file found" 2024-06-13 06:10:29,616 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 06:10:30,428 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 06:10:30,428 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:30,428 DEBUG --- stdout --- 2024-06-13 06:10:30,428 DEBUG /results/orders/Reporting-login-tree-50/stdout.txt file found 2024-06-13 06:10:30,428 DEBUG --- stderr --- 2024-06-13 06:10:30,428 DEBUG 2024-06-13 06:10:30,428 INFO 2024-06-13 06:10:30,428 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-06-13 06:10:30,429 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:30,727 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:30,727 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:30,727 DEBUG --- stdout --- 2024-06-13 06:10:30,727 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:30,727 DEBUG --- stderr --- 2024-06-13 06:10:30,727 DEBUG 2024-06-13 06:10:30,728 INFO 2024-06-13 06:10:30,728 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/Reporting-login-tree-50/stdout.txt 2024-06-13 06:10:31,520 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 06:10:31,521 DEBUG --- stdout --- 2024-06-13 06:10:31,522 DEBUG 4 /results/orders/Reporting-login-tree-50/stdout.txt 2024-06-13 06:10:31,522 DEBUG --- stderr --- 2024-06-13 06:10:31,522 DEBUG 2024-06-13 06:10:31,523 INFO - Download file (size 4k) 2024-06-13 06:10:31,523 INFO 2024-06-13 06:10:31,523 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-06-13 06:10:31,523 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:31,817 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:31,817 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:31,817 DEBUG --- stdout --- 2024-06-13 06:10:31,817 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:31,817 DEBUG --- stderr --- 2024-06-13 06:10:31,817 DEBUG source : /results/orders/Reporting-login-tree-50/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/10_Reporting-login-tree-50/stdout.txt 2024-06-13 06:10:31,818 INFO 2024-06-13 06:10:31,818 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-06-13 06:10:31,818 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:32,112 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:32,112 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:32,112 DEBUG --- stdout --- 2024-06-13 06:10:32,112 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:32,112 DEBUG --- stderr --- 2024-06-13 06:10:32,112 DEBUG 2024-06-13 06:10:32,113 INFO 2024-06-13 06:10:32,113 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/Reporting-login-tree-50/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/10_Reporting-login-tree-50/stdout.txt 2024-06-13 06:10:32,113 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 06:10:32,841 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:32,841 DEBUG --- stdout --- 2024-06-13 06:10:32,841 DEBUG tar: Removing leading `/' from member names 2024-06-13 06:10:32,841 DEBUG --- stderr --- 2024-06-13 06:10:32,841 DEBUG 2024-06-13 06:10:32,842 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/10_Reporting-login-tree-50/stdout.txt is small (88 lines), printing all lines: 2024-06-13 06:10:32,842 DEBUG ----- output ----- 2024-06-13 06:10:32,842 DEBUG [cmd] /lodestar/clients/gatling/bin/gatling.sh --reports-only /results/gatling/login-tree-50/loadtest-20240613005245772 --extra-run-jvm-options -Xmx4G 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 727521 (OK=727499 KO=22 ) > min response time 93 (OK=95 KO=93 ) > max response time 7692 (OK=7692 KO=395 ) > mean response time 208 (OK=208 KO=162 ) > std deviation 179 (OK=179 KO=83 ) > response time 50th percentile 115 (OK=115 KO=106 ) > response time 75th percentile 420 (OK=420 KO=219 ) > response time 95th percentile 476 (OK=476 KO=316 ) > response time 99th percentile 725 (OK=725 KO=379 ) > mean requests/sec 200.419 (OK=200.413 KO=0.006 ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 723780 ( 99%) > 800 ms <= t < 1200 ms 2782 ( 0%) > t >= 1200 ms 937 ( 0%) > failed 22 ( 0%) ---- Errors -------------------------------------------------------------------- > status.find.is(200), but actually found 401 8 (26.67%) > jsonPath($.tokenId).find.exists, found nothing 8 (26.67%) > status.find.is(200), but actually found 502 6 (20.00%) > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 6.67%) attribute named 'callbacks' is defined > restloginProgressiveProfileCallback: Failed to build request: 2 ( 6.67%) No attribute named 'callbacks' is defined > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 6.67%) attribute named 'callbacks' is defined > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 6.67%) attribute named 'callbacks' is defined ================================================================================ Reports generated in 0s. Please open the following file: file:///results/gatling/login-tree-50/loadtest-20240613005245772/index.html ---------------------- [13/Jun/2024 07:10:17] Create tar file /results/gatling/login-tree-50.tar.gz ---------------------- SIMULATION_PATH=/results/gatling/login-tree-50/loadtest-20240613005245772 RESULT_FOLDER=login-tree-50 RESULT_PATH=/results/gatling/login-tree-50 PWD=/results/gatling/login-tree-50/loadtest-20240613005245772 [cmd] tar -czvf /results/gatling/login-tree-50.tar.gz --exclude=simulation.log * index.html js/ js/bootstrap.min.js js/ellipsis.js js/assertions.xml js/menu.js js/jquery-3.5.1.min.js js/global_stats.json js/stats.js js/gatling.js js/all_sessions.js js/highstock.js js/stats.json js/highcharts-more.js js/unpack.js js/assertions.json js/theme.js req_pingoneprotecte-ee094.html req_pingoneprotecti-dd6e3.html req_protectloginini-66072.html req_restloginprogre-9fc93.html req_restloginuserna-d98ea.html style/ style/arrow_right_black.png style/style.css style/arrow_down_black.png style/bootstrap.min.css style/sortable.png style/arrow_down.png style/favicon.ico style/little_arrow_right.png style/sorted-down.png style/stat-fleche-bas.png style/logo-enterprise.svg style/stat-l-temps.png style/logo.svg style/arrow_right.png style/stat-l-roue.png style/sorted-up.png 2024-06-13 06:10:32,842 INFO ________________________________________________________________________________ [2024-06-13 06:10:32] Reporting-login-tree-50 step10 : Check stdout log ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [2024-06-13 06:10:32] Reporting-login-tree-50 step11 : Print stderr order ________________________________________________________________________________ 2024-06-13 06:10:32,843 INFO 2024-06-13 06:10:32,843 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-06-13 06:10:32,843 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:33,142 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:33,142 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:33,142 DEBUG --- stdout --- 2024-06-13 06:10:33,142 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:33,142 DEBUG --- stderr --- 2024-06-13 06:10:33,142 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 06:10:33,142 INFO 2024-06-13 06:10:33,143 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-06-13 06:10:33,143 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:33,443 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:33,444 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:33,444 DEBUG --- stdout --- 2024-06-13 06:10:33,444 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:33,444 DEBUG --- stderr --- 2024-06-13 06:10:33,444 DEBUG 2024-06-13 06:10:33,444 INFO 2024-06-13 06:10:33,445 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/Reporting-login-tree-50/stderr.txt" ]; then echo "/results/orders/Reporting-login-tree-50/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-login-tree-50/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-login-tree-50/stderr.txt file found" 2024-06-13 06:10:33,445 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 06:10:34,252 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 06:10:34,252 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:34,252 DEBUG --- stdout --- 2024-06-13 06:10:34,252 DEBUG /results/orders/Reporting-login-tree-50/stderr.txt file found 2024-06-13 06:10:34,252 DEBUG --- stderr --- 2024-06-13 06:10:34,252 DEBUG 2024-06-13 06:10:34,252 INFO 2024-06-13 06:10:34,252 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-06-13 06:10:34,253 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:34,562 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:34,563 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:34,563 DEBUG --- stdout --- 2024-06-13 06:10:34,563 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:34,563 DEBUG --- stderr --- 2024-06-13 06:10:34,563 DEBUG 2024-06-13 06:10:34,563 INFO 2024-06-13 06:10:34,563 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/Reporting-login-tree-50/stderr.txt 2024-06-13 06:10:35,388 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 06:10:35,389 DEBUG --- stdout --- 2024-06-13 06:10:35,389 DEBUG 0 /results/orders/Reporting-login-tree-50/stderr.txt 2024-06-13 06:10:35,389 DEBUG --- stderr --- 2024-06-13 06:10:35,389 DEBUG 2024-06-13 06:10:35,389 INFO - Download file (size 0k) 2024-06-13 06:10:35,389 INFO 2024-06-13 06:10:35,389 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-06-13 06:10:35,389 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:35,700 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:35,700 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:35,700 DEBUG --- stdout --- 2024-06-13 06:10:35,701 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:35,701 DEBUG --- stderr --- 2024-06-13 06:10:35,701 DEBUG source : /results/orders/Reporting-login-tree-50/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/10_Reporting-login-tree-50/stderr.txt 2024-06-13 06:10:35,701 INFO 2024-06-13 06:10:35,701 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-06-13 06:10:35,701 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:10:36,010 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:10:36,011 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:36,011 DEBUG --- stdout --- 2024-06-13 06:10:36,011 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:10:36,011 DEBUG --- stderr --- 2024-06-13 06:10:36,011 DEBUG 2024-06-13 06:10:36,011 INFO 2024-06-13 06:10:36,011 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/Reporting-login-tree-50/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/10_Reporting-login-tree-50/stderr.txt 2024-06-13 06:10:36,011 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 06:10:36,793 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:10:36,793 DEBUG --- stdout --- 2024-06-13 06:10:36,793 DEBUG tar: Removing leading `/' from member names 2024-06-13 06:10:36,793 DEBUG --- stderr --- 2024-06-13 06:10:36,793 DEBUG 2024-06-13 06:10:36,794 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/10_Reporting-login-tree-50/stderr.txt is small (0 lines), printing all lines: 2024-06-13 06:10:36,794 DEBUG ----- output ----- 2024-06-13 06:10:36,794 DEBUG 2024-06-13 06:10:36,794 INFO ________________________________________________________________________________ [2024-06-13 06:10:36] Reporting-login-tree-50 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [2024-06-13 06:10:36] Reporting-login-tree-50 step13 : Analyze graphs for spikes ________________________________________________________________________________ Analyze Gatling graphs for spikes checking parameters open overall stats JSON file checking simulation request protectLoginInitiate [warn] responseTimePercentiles was not found in /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/gatling/req_protectloginini-66072.html - cannot check for spikes checking simulation request PingOneProtectInitializeCallback [warn] responseTimePercentiles was not found in /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/gatling/req_pingoneprotecti-dd6e3.html - cannot check for spikes checking simulation request restLoginUsernamePasswordCallback [warn] responseTimePercentiles was not found in /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/gatling/req_restloginuserna-d98ea.html - cannot check for spikes checking simulation request PingOneProtectEvaluationCallback [warn] responseTimePercentiles was not found in /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/gatling/req_pingoneprotecte-ee094.html - cannot check for spikes checking simulation request restloginProgressiveProfileCallback no spikes found ________________________________________________________________________________ [2024-06-13 06:10:36] Reporting-login-tree-50 step14 : Collect Gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "All Requests", "numberOfRequests": { "total": 727521, "ok": 727499, "ko": 22 }, "minResponseTime": { "total": 93, "ok": 95, "ko": 93 }, "maxResponseTime": { "total": 7692, "ok": 7692, "ko": 395 }, "meanResponseTime": { "total": 208, "ok": 208, "ko": 162 }, "standardDeviation": { "total": 179, "ok": 179, "ko": 83 }, "percentiles1": { "total": 115, "ok": 115, "ko": 106 }, "percentiles2": { "total": 420, "ok": 420, "ko": 219 }, "percentiles3": { "total": 476, "ok": 476, "ko": 316 }, "percentiles4": { "total": 725, "ok": 725, "ko": 379 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 723780, "percentage": 99 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 2782, "percentage": 0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 937, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 22, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 200.4190082644628, "ok": 200.4129476584022, "ko": 0.006060606060606061 } }, "details": [ { "name": "protectLoginInitiate", "numberOfRequests": { "total": 181519, "ok": 181517, "ko": 2 }, "minResponseTime": { "total": 95, "ok": 98, "ko": 95 }, "maxResponseTime": { "total": 3218, "ok": 3218, "ko": 96 }, "meanResponseTime": { "total": 108, "ok": 108, "ko": 96 }, "standardDeviation": { "total": 27, "ok": 27, "ko": 1 }, "percentiles1": { "total": 108, "ok": 108, "ko": 96 }, "percentiles2": { "total": 110, "ok": 110, "ko": 96 }, "percentiles3": { "total": 113, "ok": 113, "ko": 96 }, "percentiles4": { "total": 118, "ok": 118, "ko": 96 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 181469, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 35, "percentage": 0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 13, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 2, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 50.00523415977961, "ok": 50.004683195592285, "ko": 0.0005509641873278236 }, "spikes": [] }, { "name": "PingOneProtectInitializeCallback", "numberOfRequests": { "total": 181517, "ok": 181517, "ko": 0 }, "minResponseTime": { "total": 95, "ok": 95, "ko": 0 }, "maxResponseTime": { "total": 3205, "ok": 3205, "ko": 0 }, "meanResponseTime": { "total": 114, "ok": 114, "ko": 0 }, "standardDeviation": { "total": 38, "ok": 38, "ko": 0 }, "percentiles1": { "total": 110, "ok": 110, "ko": 0 }, "percentiles2": { "total": 113, "ok": 113, "ko": 0 }, "percentiles3": { "total": 118, "ok": 118, "ko": 0 }, "percentiles4": { "total": 265, "ok": 265, "ko": 0 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 181508, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 2, "percentage": 0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 7, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 50.004683195592285, "ok": 50.004683195592285, "ko": 0 }, "spikes": [] }, { "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "total": 181517, "ok": 181516, "ko": 1 }, "minResponseTime": { "total": 103, "ok": 103, "ko": 320 }, "maxResponseTime": { "total": 3183, "ok": 3183, "ko": 320 }, "meanResponseTime": { "total": 126, "ok": 126, "ko": 320 }, "standardDeviation": { "total": 34, "ok": 34, "ko": 0 }, "percentiles1": { "total": 123, "ok": 123, "ko": 320 }, "percentiles2": { "total": 127, "ok": 127, "ko": 320 }, "percentiles3": { "total": 133, "ok": 133, "ko": 320 }, "percentiles4": { "total": 330, "ok": 330, "ko": 320 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 181508, "percentage": 100 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 2, "percentage": 0 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 6, "percentage": 0 }, "group4": { "name": "failed", "htmlName": "failed", "count": 1, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 50.004683195592285, "ok": 50.00440771349862, "ko": 0.0002754820936639118 }, "spikes": [] }, { "name": "PingOneProtectEvaluationCallback", "numberOfRequests": { "total": 181517, "ok": 181506, "ko": 11 }, "minResponseTime": { "total": 93, "ok": 133, "ko": 93 }, "maxResponseTime": { "total": 7692, "ok": 7692, "ko": 395 }, "meanResponseTime": { "total": 483, "ok": 483, "ko": 202 }, "standardDeviation": { "total": 157, "ok": 157, "ko": 82 }, "percentiles1": { "total": 448, "ok": 448, "ko": 216 }, "percentiles2": { "total": 467, "ok": 467, "ko": 225 }, "percentiles3": { "total": 667, "ok": 667, "ko": 314 }, "percentiles4": { "total": 929, "ok": 929, "ko": 379 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 177852, "percentage": 98 }, "group2": { "name": "800 ms <= t < 1200 ms", "htmlName": "t >= 800 ms
t < 1200 ms", "count": 2743, "percentage": 2 }, "group3": { "name": "t >= 1200 ms", "htmlName": "t >= 1200 ms", "count": 911, "percentage": 1 }, "group4": { "name": "failed", "htmlName": "failed", "count": 11, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 50.004683195592285, "ok": 50.001652892561985, "ko": 0.0030303030303030303 }, "spikes": [] }, { "name": "restloginProgressiveProfileCallback", "numberOfRequests": { "total": 1451, "ok": 1443, "ko": 8 }, "minResponseTime": { "total": 96, "ok": 174, "ko": 96 }, "maxResponseTime": { "total": 674, "ok": 674, "ko": 111 }, "meanResponseTime": { "total": 209, "ok": 210, "ko": 104 }, "standardDeviation": { "total": 26, "ok": 24, "ko": 4 }, "percentiles1": { "total": 202, "ok": 202, "ko": 105 }, "percentiles2": { "total": 222, "ok": 222, "ko": 106 }, "percentiles3": { "total": 245, "ok": 245, "ko": 110 }, "percentiles4": { "total": 265, "ok": 265, "ko": 111 }, "group1": { "name": "t < 800 ms", "htmlName": "t < 800 ms", "count": 1443, "percentage": 99 }, "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": 8, "percentage": 1 }, "meanNumberOfRequestsPerSecond": { "total": 0.39972451790633606, "ok": 0.3975206611570248, "ko": 0.0022038567493112946 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 200, "avg_response_time": 208, "max_response_time": 7692, "min_response_time": 95, "num_requests": 727521, "num_requests_pass": 727499, "num_requests_percent_pass": 99.997 }, "options": "; testCase:idc.loginProtect; testMode:open; throughput:50; rampup-duration:30; duration:3600", "stats": { "details": [ { "group1": { "count": 181469, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 35, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 13, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 2, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 96, "ok": 3218, "total": 3218 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0005509641873278236, "ok": 50.004683195592285, "total": 50.00523415977961 }, "meanResponseTime": { "ko": 96, "ok": 108, "total": 108 }, "minResponseTime": { "ko": 95, "ok": 98, "total": 95 }, "name": "protectLoginInitiate", "numberOfRequests": { "ko": 2, "ok": 181517, "total": 181519 }, "percentiles1": { "ko": 96, "ok": 108, "total": 108 }, "percentiles2": { "ko": 96, "ok": 110, "total": 110 }, "percentiles3": { "ko": 96, "ok": 113, "total": 113 }, "percentiles4": { "ko": 96, "ok": 118, "total": 118 }, "spikes": [], "standardDeviation": { "ko": 1, "ok": 27, "total": 27 } }, { "group1": { "count": 181508, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 2, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 7, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 0, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 3205, "total": 3205 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 50.004683195592285, "total": 50.004683195592285 }, "meanResponseTime": { "ko": 0, "ok": 114, "total": 114 }, "minResponseTime": { "ko": 0, "ok": 95, "total": 95 }, "name": "PingOneProtectInitializeCallback", "numberOfRequests": { "ko": 0, "ok": 181517, "total": 181517 }, "percentiles1": { "ko": 0, "ok": 110, "total": 110 }, "percentiles2": { "ko": 0, "ok": 113, "total": 113 }, "percentiles3": { "ko": 0, "ok": 118, "total": 118 }, "percentiles4": { "ko": 0, "ok": 265, "total": 265 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 38, "total": 38 } }, { "group1": { "count": 181508, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 2, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 6, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 1, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 320, "ok": 3183, "total": 3183 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0002754820936639118, "ok": 50.00440771349862, "total": 50.004683195592285 }, "meanResponseTime": { "ko": 320, "ok": 126, "total": 126 }, "minResponseTime": { "ko": 320, "ok": 103, "total": 103 }, "name": "restLoginUsernamePasswordCallback", "numberOfRequests": { "ko": 1, "ok": 181516, "total": 181517 }, "percentiles1": { "ko": 320, "ok": 123, "total": 123 }, "percentiles2": { "ko": 320, "ok": 127, "total": 127 }, "percentiles3": { "ko": 320, "ok": 133, "total": 133 }, "percentiles4": { "ko": 320, "ok": 330, "total": 330 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 34, "total": 34 } }, { "group1": { "count": 177852, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 98 }, "group2": { "count": 2743, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 2 }, "group3": { "count": 911, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 1 }, "group4": { "count": 11, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 395, "ok": 7692, "total": 7692 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0030303030303030303, "ok": 50.001652892561985, "total": 50.004683195592285 }, "meanResponseTime": { "ko": 202, "ok": 483, "total": 483 }, "minResponseTime": { "ko": 93, "ok": 133, "total": 93 }, "name": "PingOneProtectEvaluationCallback", "numberOfRequests": { "ko": 11, "ok": 181506, "total": 181517 }, "percentiles1": { "ko": 216, "ok": 448, "total": 448 }, "percentiles2": { "ko": 225, "ok": 467, "total": 467 }, "percentiles3": { "ko": 314, "ok": 667, "total": 667 }, "percentiles4": { "ko": 379, "ok": 929, "total": 929 }, "spikes": [], "standardDeviation": { "ko": 82, "ok": 157, "total": 157 } }, { "group1": { "count": 1443, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99 }, "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": 8, "htmlName": "failed", "name": "failed", "percentage": 1 }, "maxResponseTime": { "ko": 111, "ok": 674, "total": 674 }, "meanNumberOfRequestsPerSecond": { "ko": 0.0022038567493112946, "ok": 0.3975206611570248, "total": 0.39972451790633606 }, "meanResponseTime": { "ko": 104, "ok": 210, "total": 209 }, "minResponseTime": { "ko": 96, "ok": 174, "total": 96 }, "name": "restloginProgressiveProfileCallback", "numberOfRequests": { "ko": 8, "ok": 1443, "total": 1451 }, "percentiles1": { "ko": 105, "ok": 202, "total": 202 }, "percentiles2": { "ko": 106, "ok": 222, "total": 222 }, "percentiles3": { "ko": 110, "ok": 245, "total": 245 }, "percentiles4": { "ko": 111, "ok": 265, "total": 265 }, "spikes": [], "standardDeviation": { "ko": 4, "ok": 24, "total": 26 } } ], "global": { "group1": { "count": 723780, "htmlName": "t < 800 ms", "name": "t < 800 ms", "percentage": 99 }, "group2": { "count": 2782, "htmlName": "t >= 800 ms
t < 1200 ms", "name": "800 ms <= t < 1200 ms", "percentage": 0 }, "group3": { "count": 937, "htmlName": "t >= 1200 ms", "name": "t >= 1200 ms", "percentage": 0 }, "group4": { "count": 22, "htmlName": "failed", "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 395, "ok": 7692, "total": 7692 }, "meanNumberOfRequestsPerSecond": { "ko": 0.006060606060606061, "ok": 200.4129476584022, "total": 200.4190082644628 }, "meanResponseTime": { "ko": 162, "ok": 208, "total": 208 }, "minResponseTime": { "ko": 93, "ok": 95, "total": 93 }, "name": "All Requests", "numberOfRequests": { "ko": 22, "ok": 727499, "total": 727521 }, "percentiles1": { "ko": 106, "ok": 115, "total": 115 }, "percentiles2": { "ko": 219, "ok": 420, "total": 420 }, "percentiles3": { "ko": 316, "ok": 476, "total": 476 }, "percentiles4": { "ko": 379, "ok": 725, "total": 725 }, "standardDeviation": { "ko": 83, "ok": 179, "total": 179 } } }, "target_hostname": "openam-ema-perf-p1protect.forgeblocks.com", "tool_name": "gatling" } ________________________________________________________________________________ [2024-06-13 06:10:36] Reporting-login-tree-50 step15 : Update run description in the Gatling HTML report ________________________________________________________________________________ ________________________________________________________________________________ [2024-06-13 06:10:36] Reporting-login-tree-50 step16 : Remove gatling task's stdout file ________________________________________________________________________________ Cleanup of Gatling stdout file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/stdout.txt Compressing Gatling stdout.txt file.. ________________________________________________________________________________ [2024-06-13 06:10:36] Reporting-login-tree-50 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped