--Task--
name: Reporting-login-tree-330
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', 'Reporting-login-tree-100', 'Reporting-login-tree-150', 'Reporting-login-tree-200', 'Reporting-login-tree-250', 'Reporting-login-tree-300']
preceding_task: None
options: {'task_name': 'login-tree-330'}
group_name: None
Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock
________________________________________________________________________________
[2024-06-23 11:07:40] Reporting-login-tree-330 pre : N/A
________________________________________________________________________________
source (overseer-0) is a component
2024-06-23 11:07:40 - INFO: Timeout for this Task has changed to 3h 30m (12600 seconds)
2024-06-23 11:07:40 - 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-330/stdout.txt
Pod stderr file : /results/orders/Reporting-login-tree-330/stderr.txt
Pod log : /results/orders/Reporting-login-tree-330
________________________________________________________________________________
[2024-06-23 11:07:40] Reporting-login-tree-330 step1 : Write order for overseer to generate the report
________________________________________________________________________________
2024-06-23 11:07:40,933 INFO
2024-06-23 11:07:40,934 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-23 11:07:40,934 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:07:41,262 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:07:41,263 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:07:41,264 DEBUG --- stdout ---
2024-06-23 11:07:41,265 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:07:41,265 DEBUG --- stderr ---
2024-06-23 11:07:41,265 DEBUG
Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/15_Reporting-login-tree-330/order.json
{
"name": "Reporting-login-tree-330",
"command": "/lodestar/clients/gatling-report-only-for-pyrock.sh login-tree-330 --extra-run-jvm-options \"-Xmx4G\"",
"out-dir": "/results/orders/Reporting-login-tree-330"
}
________________________________________________________________________________
[2024-06-23 11:07:41] Reporting-login-tree-330 step2 : Make sure overseer is ready to accept order (not already busy)
________________________________________________________________________________
2024-06-23 11:07:41,266 INFO
2024-06-23 11:07:41,266 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING"
2024-06-23 11:07:41,267 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0]
2024-06-23 11:07:41,510 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-23 11:07:41,510 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:07:41,510 DEBUG --- stdout ---
2024-06-23 11:07:41,510 DEBUG WAITING
2024-06-23 11:07:41,510 DEBUG --- stderr ---
2024-06-23 11:07:41,510 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current
Dload Upload Total Spent Left Speed
0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0
0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0
100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32
________________________________________________________________________________
[2024-06-23 11:07:41] Reporting-login-tree-330 step3 : Send order to overseer
________________________________________________________________________________
2024-06-23 11:07:41,511 INFO
2024-06-23 11:07:41,511 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-23 11:07:41,512 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:07:41,835 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:07:41,836 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:07:41,836 DEBUG --- stdout ---
2024-06-23 11:07:41,836 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:07:41,836 DEBUG --- stderr ---
2024-06-23 11:07:41,836 DEBUG
2024-06-23 11:07:41,837 INFO
2024-06-23 11:07:41,837 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/15_Reporting-login-tree-330/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json
2024-06-23 11:07:41,837 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-23 11:07:43,223 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:07:43,223 DEBUG --- stdout ---
2024-06-23 11:07:43,224 DEBUG
2024-06-23 11:07:43,224 DEBUG --- stderr ---
2024-06-23 11:07:43,224 DEBUG
Order has been successfully forwarded to overseer
________________________________________________________________________________
[2024-06-23 11:07:43] Reporting-login-tree-330 step4 : Wait order is done
________________________________________________________________________________
Workaround for PERF-1966. Sleeping for 5 seconds.
2024-06-23 11:07:48,226 INFO
2024-06-23 11:07:48,227 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING"
2024-06-23 11:07:48,227 INFO [loop_until]: (max_time=12600, interval=5, expected_rc=[0]
2024-06-23 11:07:48,473 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry
2024-06-23 11:07:53,772 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry
2024-06-23 11:07:59,009 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry
2024-06-23 11:08:04,256 INFO [loop_until]: Function succeeded after 16s (rc=0) - failed to find expected output: WAITING - retry
2024-06-23 11:08:09,504 INFO [loop_until]: Function succeeded after 21s (rc=0) - expected pattern found
2024-06-23 11:08:09,505 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:09,505 DEBUG --- stdout ---
2024-06-23 11:08:09,505 DEBUG WAITING
2024-06-23 11:08:09,505 DEBUG --- stderr ---
2024-06-23 11:08:09,505 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current
Dload Upload Total Spent Left Speed
0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0
0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0
100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32
________________________________________________________________________________
[2024-06-23 11:08:09] Reporting-login-tree-330 step5 : Wait until gatling report is generated on overseer
________________________________________________________________________________
2024-06-23 11:08:09,506 INFO
2024-06-23 11:08:09,506 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-23 11:08:09,506 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:09,824 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:09,824 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:09,824 DEBUG --- stdout ---
2024-06-23 11:08:09,824 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:09,824 DEBUG --- stderr ---
2024-06-23 11:08:09,824 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-23 11:08:09,825 INFO
2024-06-23 11:08:09,825 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-23 11:08:09,825 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:10,144 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:10,144 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:10,144 DEBUG --- stdout ---
2024-06-23 11:08:10,144 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:10,144 DEBUG --- stderr ---
2024-06-23 11:08:10,144 DEBUG
2024-06-23 11:08:10,145 INFO
2024-06-23 11:08:10,145 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/gatling/login-tree-330.tar.gz" ]; then echo "/results/gatling/login-tree-330.tar.gz file found"; exit 0; else echo "/results/gatling/login-tree-330.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/login-tree-330.tar.gz file found"
2024-06-23 11:08:10,145 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-23 11:08:10,967 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-23 11:08:10,967 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:10,967 DEBUG --- stdout ---
2024-06-23 11:08:10,967 DEBUG /results/gatling/login-tree-330.tar.gz file found
2024-06-23 11:08:10,968 DEBUG --- stderr ---
2024-06-23 11:08:10,968 DEBUG
2024-06-23 11:08:10,968 INFO
2024-06-23 11:08:10,968 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-23 11:08:10,968 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:11,291 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:11,292 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:11,292 DEBUG --- stdout ---
2024-06-23 11:08:11,292 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:11,292 DEBUG --- stderr ---
2024-06-23 11:08:11,292 DEBUG
2024-06-23 11:08:11,292 INFO
2024-06-23 11:08:11,292 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/gatling/login-tree-330.tar.gz
2024-06-23 11:08:12,105 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-23 11:08:12,105 DEBUG --- stdout ---
2024-06-23 11:08:12,105 DEBUG 468 /results/gatling/login-tree-330.tar.gz
2024-06-23 11:08:12,105 DEBUG --- stderr ---
2024-06-23 11:08:12,105 DEBUG
2024-06-23 11:08:12,105 INFO
- Download file (size 468k)
2024-06-23 11:08:12,106 INFO
2024-06-23 11:08:12,106 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-23 11:08:12,106 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:12,425 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:12,425 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:12,425 DEBUG --- stdout ---
2024-06-23 11:08:12,425 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:12,425 DEBUG --- stderr ---
2024-06-23 11:08:12,425 DEBUG
source : /results/gatling/login-tree-330.tar.gz @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/shared/login-tree-330.tar.gz
2024-06-23 11:08:12,426 INFO
2024-06-23 11:08:12,426 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-23 11:08:12,426 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:12,745 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:12,745 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:12,745 DEBUG --- stdout ---
2024-06-23 11:08:12,745 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:12,745 DEBUG --- stderr ---
2024-06-23 11:08:12,745 DEBUG
2024-06-23 11:08:12,746 INFO
2024-06-23 11:08:12,746 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/gatling/login-tree-330.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/shared/login-tree-330.tar.gz
2024-06-23 11:08:12,746 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-23 11:08:13,802 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:13,802 DEBUG --- stdout ---
2024-06-23 11:08:13,802 DEBUG tar: Removing leading `/' from member names
2024-06-23 11:08:13,802 DEBUG --- stderr ---
2024-06-23 11:08:13,802 DEBUG
________________________________________________________________________________
[2024-06-23 11:08:13] Reporting-login-tree-330 step6 : Unzip the result
________________________________________________________________________________
2024-06-23 11:08:13,803 INFO
2024-06-23 11:08:13,803 INFO [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/shared/login-tree-330.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/gatling
2024-06-23 11:08:13,839 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-23 11:08:13,839 DEBUG --- stdout ---
2024-06-23 11:08:13,840 DEBUG
2024-06-23 11:08:13,840 DEBUG --- stderr ---
2024-06-23 11:08:13,840 DEBUG
2024-06-23 11:08:13,840 INFO
________________________________________________________________________________
[2024-06-23 11:08:13] Reporting-login-tree-330 step7 : Print rc order
________________________________________________________________________________
2024-06-23 11:08:13,841 INFO
2024-06-23 11:08:13,841 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-23 11:08:13,841 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:14,180 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:14,180 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:14,180 DEBUG --- stdout ---
2024-06-23 11:08:14,181 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:14,181 DEBUG --- stderr ---
2024-06-23 11:08:14,181 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-23 11:08:14,181 INFO
2024-06-23 11:08:14,181 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-23 11:08:14,181 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:14,503 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:14,503 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:14,503 DEBUG --- stdout ---
2024-06-23 11:08:14,504 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:14,504 DEBUG --- stderr ---
2024-06-23 11:08:14,504 DEBUG
2024-06-23 11:08:14,505 INFO
2024-06-23 11:08:14,505 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/Reporting-login-tree-330/rc.txt" ]; then echo "/results/orders/Reporting-login-tree-330/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-login-tree-330/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-login-tree-330/rc.txt file found"
2024-06-23 11:08:14,505 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-23 11:08:15,327 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-23 11:08:15,327 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:15,328 DEBUG --- stdout ---
2024-06-23 11:08:15,328 DEBUG /results/orders/Reporting-login-tree-330/rc.txt file found
2024-06-23 11:08:15,328 DEBUG --- stderr ---
2024-06-23 11:08:15,328 DEBUG
2024-06-23 11:08:15,328 INFO
2024-06-23 11:08:15,328 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-23 11:08:15,328 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:15,670 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:15,670 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:15,670 DEBUG --- stdout ---
2024-06-23 11:08:15,670 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:15,670 DEBUG --- stderr ---
2024-06-23 11:08:15,670 DEBUG
2024-06-23 11:08:15,670 INFO
2024-06-23 11:08:15,671 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/Reporting-login-tree-330/rc.txt
2024-06-23 11:08:16,500 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-23 11:08:16,500 DEBUG --- stdout ---
2024-06-23 11:08:16,500 DEBUG 4 /results/orders/Reporting-login-tree-330/rc.txt
2024-06-23 11:08:16,500 DEBUG --- stderr ---
2024-06-23 11:08:16,500 DEBUG
2024-06-23 11:08:16,500 INFO
- Download file (size 4k)
2024-06-23 11:08:16,501 INFO
2024-06-23 11:08:16,501 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-23 11:08:16,501 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:16,817 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:16,817 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:16,818 DEBUG --- stdout ---
2024-06-23 11:08:16,819 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:16,819 DEBUG --- stderr ---
2024-06-23 11:08:16,819 DEBUG
source : /results/orders/Reporting-login-tree-330/rc.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/15_Reporting-login-tree-330/rc.txt
2024-06-23 11:08:16,819 INFO
2024-06-23 11:08:16,819 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-23 11:08:16,819 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:17,122 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:17,123 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:17,123 DEBUG --- stdout ---
2024-06-23 11:08:17,123 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:17,123 DEBUG --- stderr ---
2024-06-23 11:08:17,123 DEBUG
2024-06-23 11:08:17,123 INFO
2024-06-23 11:08:17,123 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/Reporting-login-tree-330/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/15_Reporting-login-tree-330/rc.txt
2024-06-23 11:08:17,123 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-23 11:08:17,857 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:17,857 DEBUG --- stdout ---
2024-06-23 11:08:17,858 DEBUG tar: Removing leading `/' from member names
2024-06-23 11:08:17,858 DEBUG --- stderr ---
2024-06-23 11:08:17,858 DEBUG
2024-06-23 11:08:17,858 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/15_Reporting-login-tree-330/rc.txt is small (1 lines), printing all lines:
2024-06-23 11:08:17,858 DEBUG ----- output -----
2024-06-23 11:08:17,858 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15])
2024-06-23 11:08:17,858 INFO
________________________________________________________________________________
[2024-06-23 11:08:17] Reporting-login-tree-330 step8 : Check rc log
________________________________________________________________________________
PASS: no error found in rc log
________________________________________________________________________________
[2024-06-23 11:08:17] Reporting-login-tree-330 step9 : Print stdout order
________________________________________________________________________________
2024-06-23 11:08:17,859 INFO
2024-06-23 11:08:17,859 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-23 11:08:17,859 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:18,163 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:18,164 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:18,164 DEBUG --- stdout ---
2024-06-23 11:08:18,164 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:18,164 DEBUG --- stderr ---
2024-06-23 11:08:18,164 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-23 11:08:18,164 INFO
2024-06-23 11:08:18,164 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-23 11:08:18,164 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:18,468 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:18,469 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:18,469 DEBUG --- stdout ---
2024-06-23 11:08:18,469 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:18,469 DEBUG --- stderr ---
2024-06-23 11:08:18,469 DEBUG
2024-06-23 11:08:18,470 INFO
2024-06-23 11:08:18,470 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/Reporting-login-tree-330/stdout.txt" ]; then echo "/results/orders/Reporting-login-tree-330/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-login-tree-330/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-login-tree-330/stdout.txt file found"
2024-06-23 11:08:18,470 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-23 11:08:19,314 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-23 11:08:19,314 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:19,314 DEBUG --- stdout ---
2024-06-23 11:08:19,314 DEBUG /results/orders/Reporting-login-tree-330/stdout.txt file found
2024-06-23 11:08:19,314 DEBUG --- stderr ---
2024-06-23 11:08:19,314 DEBUG
2024-06-23 11:08:19,315 INFO
2024-06-23 11:08:19,315 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-23 11:08:19,315 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:19,624 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:19,624 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:19,624 DEBUG --- stdout ---
2024-06-23 11:08:19,624 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:19,624 DEBUG --- stderr ---
2024-06-23 11:08:19,624 DEBUG
2024-06-23 11:08:19,624 INFO
2024-06-23 11:08:19,624 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/Reporting-login-tree-330/stdout.txt
2024-06-23 11:08:20,423 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-23 11:08:20,423 DEBUG --- stdout ---
2024-06-23 11:08:20,423 DEBUG 8 /results/orders/Reporting-login-tree-330/stdout.txt
2024-06-23 11:08:20,423 DEBUG --- stderr ---
2024-06-23 11:08:20,424 DEBUG
2024-06-23 11:08:20,424 INFO
- Download file (size 8k)
2024-06-23 11:08:20,424 INFO
2024-06-23 11:08:20,424 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-23 11:08:20,424 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:20,748 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:20,749 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:20,749 DEBUG --- stdout ---
2024-06-23 11:08:20,749 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:20,749 DEBUG --- stderr ---
2024-06-23 11:08:20,749 DEBUG
source : /results/orders/Reporting-login-tree-330/stdout.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/15_Reporting-login-tree-330/stdout.txt
2024-06-23 11:08:20,749 INFO
2024-06-23 11:08:20,749 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-23 11:08:20,749 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:21,079 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:21,079 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:21,079 DEBUG --- stdout ---
2024-06-23 11:08:21,079 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:21,079 DEBUG --- stderr ---
2024-06-23 11:08:21,079 DEBUG
2024-06-23 11:08:21,080 INFO
2024-06-23 11:08:21,080 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/Reporting-login-tree-330/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/15_Reporting-login-tree-330/stdout.txt
2024-06-23 11:08:21,080 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-23 11:08:21,883 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:21,883 DEBUG --- stdout ---
2024-06-23 11:08:21,883 DEBUG tar: Removing leading `/' from member names
2024-06-23 11:08:21,883 DEBUG --- stderr ---
2024-06-23 11:08:21,884 DEBUG
2024-06-23 11:08:21,884 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/15_Reporting-login-tree-330/stdout.txt is small (95 lines), printing all lines:
2024-06-23 11:08:21,884 DEBUG ----- output -----
2024-06-23 11:08:21,884 DEBUG [cmd] /lodestar/clients/gatling/bin/gatling.sh --reports-only /results/gatling/login-tree-330/loadtest-20240623095925474 --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 4881696 (OK=4682546 KO=199150)
> min response time 3 (OK=40 KO=3 )
> max response time 60021 (OK=59871 KO=60021 )
> mean response time 5510 (OK=5659 KO=2014 )
> std deviation 4979 (OK=4957 KO=4157 )
> response time 50th percentile 4726 (OK=4929 KO=39 )
> response time 75th percentile 8711 (OK=8835 KO=1512 )
> response time 95th percentile 14748 (OK=14856 KO=11894 )
> response time 99th percentile 19377 (OK=19451 KO=16042 )
> mean requests/sec 1316.886 (OK=1263.163 KO=53.723)
---- Response Time Distribution ------------------------------------------------
> t < 800 ms 1103574 ( 23%)
> 800 ms <= t < 1200 ms 125673 ( 3%)
> t >= 1200 ms 3453299 ( 71%)
> failed 199150 ( 4%)
---- Errors --------------------------------------------------------------------
> status.find.is(200), but actually found 503 152276 (46.38%)
> status.find.is(200), but actually found 502 35696 (10.87%)
> PingOneProtectEvaluationCallback: Failed to build request: No 32299 ( 9.84%)
attribute named 'callbacks' is defined
> restLoginUsernamePasswordCallback: Failed to build request: No 32299 ( 9.84%)
attribute named 'callbacks' is defined
> restloginProgressiveProfileCallback: Failed to build request: 32299 ( 9.84%)
No attribute named 'callbacks' is defined
> PingOneProtectInitializeCallback: Failed to build request: No 32299 ( 9.84%)
attribute named 'callbacks' is defined
> jsonPath($.tokenId).find.exists, found nothing 5824 ( 1.77%)
> status.find.is(200), but actually found 400 3357 ( 1.02%)
> status.find.is(200), but actually found 401 1829 ( 0.56%)
> Request timeout to openam-ema-perf-p1.forgeblocks.com/35.241.5 141 ( 0.04%)
9.5:443 after 60000 ms
> status.find.is(200), but actually found 500 19 ( 0.01%)
> j.i.IOException: Premature close 7 ( 0.00%)
> j.n.c.ClosedChannelException 1 ( 0.00%)
================================================================================
Reports generated in 0s.
Please open the following file: file:///results/gatling/login-tree-330/loadtest-20240623095925474/index.html
----------------------
[23/Jun/2024 12:08:06] Create tar file /results/gatling/login-tree-330.tar.gz
----------------------
SIMULATION_PATH=/results/gatling/login-tree-330/loadtest-20240623095925474
RESULT_FOLDER=login-tree-330
RESULT_PATH=/results/gatling/login-tree-330
PWD=/results/gatling/login-tree-330/loadtest-20240623095925474
[cmd] tar -czvf /results/gatling/login-tree-330.tar.gz --exclude=simulation.log *
index.html
js/
js/gatling.js
js/highcharts-more.js
js/highstock.js
js/ellipsis.js
js/bootstrap.min.js
js/stats.json
js/stats.js
js/global_stats.json
js/all_sessions.js
js/assertions.xml
js/menu.js
js/theme.js
js/unpack.js
js/jquery-3.5.1.min.js
js/assertions.json
req_pingoneprotecte-ee094.html
req_pingoneprotecti-dd6e3.html
req_protectloginini-66072.html
req_restloginprogre-9fc93.html
req_restloginuserna-d98ea.html
style/
style/bootstrap.min.css
style/stat-l-temps.png
style/favicon.ico
style/style.css
style/arrow_down_black.png
style/sorted-down.png
style/arrow_right.png
style/stat-l-roue.png
style/stat-fleche-bas.png
style/sorted-up.png
style/logo-enterprise.svg
style/little_arrow_right.png
style/logo.svg
style/sortable.png
style/arrow_down.png
style/arrow_right_black.png
2024-06-23 11:08:21,884 INFO
________________________________________________________________________________
[2024-06-23 11:08:21] Reporting-login-tree-330 step10 : Check stdout log
________________________________________________________________________________
PASS: no error found in stdout log
________________________________________________________________________________
[2024-06-23 11:08:21] Reporting-login-tree-330 step11 : Print stderr order
________________________________________________________________________________
2024-06-23 11:08:21,887 INFO
2024-06-23 11:08:21,887 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-23 11:08:21,887 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:22,206 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:22,206 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:22,206 DEBUG --- stdout ---
2024-06-23 11:08:22,207 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:22,207 DEBUG --- stderr ---
2024-06-23 11:08:22,207 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-23 11:08:22,207 INFO
2024-06-23 11:08:22,207 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-23 11:08:22,207 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:22,529 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:22,529 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:22,529 DEBUG --- stdout ---
2024-06-23 11:08:22,529 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:22,529 DEBUG --- stderr ---
2024-06-23 11:08:22,529 DEBUG
2024-06-23 11:08:22,530 INFO
2024-06-23 11:08:22,530 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/Reporting-login-tree-330/stderr.txt" ]; then echo "/results/orders/Reporting-login-tree-330/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-login-tree-330/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-login-tree-330/stderr.txt file found"
2024-06-23 11:08:22,530 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-23 11:08:23,376 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-23 11:08:23,376 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:23,376 DEBUG --- stdout ---
2024-06-23 11:08:23,377 DEBUG /results/orders/Reporting-login-tree-330/stderr.txt file found
2024-06-23 11:08:23,377 DEBUG --- stderr ---
2024-06-23 11:08:23,377 DEBUG
2024-06-23 11:08:23,377 INFO
2024-06-23 11:08:23,377 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-06-23 11:08:23,377 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:23,693 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:23,693 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:23,693 DEBUG --- stdout ---
2024-06-23 11:08:23,693 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:23,693 DEBUG --- stderr ---
2024-06-23 11:08:23,693 DEBUG
2024-06-23 11:08:23,694 INFO
2024-06-23 11:08:23,694 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/Reporting-login-tree-330/stderr.txt
2024-06-23 11:08:24,509 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-23 11:08:24,510 DEBUG --- stdout ---
2024-06-23 11:08:24,510 DEBUG 0 /results/orders/Reporting-login-tree-330/stderr.txt
2024-06-23 11:08:24,510 DEBUG --- stderr ---
2024-06-23 11:08:24,510 DEBUG
2024-06-23 11:08:24,510 INFO
- Download file (size 0k)
2024-06-23 11:08:24,510 INFO
2024-06-23 11:08:24,510 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-06-23 11:08:24,511 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:24,818 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:24,818 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:24,818 DEBUG --- stdout ---
2024-06-23 11:08:24,818 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:24,818 DEBUG --- stderr ---
2024-06-23 11:08:24,818 DEBUG
source : /results/orders/Reporting-login-tree-330/stderr.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/15_Reporting-login-tree-330/stderr.txt
2024-06-23 11:08:24,819 INFO
2024-06-23 11:08:24,819 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-23 11:08:24,819 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:08:25,132 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:08:25,133 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:25,133 DEBUG --- stdout ---
2024-06-23 11:08:25,133 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:08:25,133 DEBUG --- stderr ---
2024-06-23 11:08:25,142 DEBUG
2024-06-23 11:08:25,145 INFO
2024-06-23 11:08:25,145 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/Reporting-login-tree-330/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/15_Reporting-login-tree-330/stderr.txt
2024-06-23 11:08:25,145 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-23 11:08:26,009 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:08:26,009 DEBUG --- stdout ---
2024-06-23 11:08:26,009 DEBUG tar: Removing leading `/' from member names
2024-06-23 11:08:26,009 DEBUG --- stderr ---
2024-06-23 11:08:26,009 DEBUG
2024-06-23 11:08:26,009 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/08_Verification/15_Reporting-login-tree-330/stderr.txt is small (0 lines), printing all lines:
2024-06-23 11:08:26,009 DEBUG ----- output -----
2024-06-23 11:08:26,009 DEBUG
2024-06-23 11:08:26,009 INFO
________________________________________________________________________________
[2024-06-23 11:08:26] Reporting-login-tree-330 step12 : Check stderr log
________________________________________________________________________________
PASS: no error found in stderr log
________________________________________________________________________________
[2024-06-23 11:08:26] Reporting-login-tree-330 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/07_login-tree-330/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/07_login-tree-330/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/07_login-tree-330/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/07_login-tree-330/gatling/req_pingoneprotecte-ee094.html - cannot check for spikes
checking simulation request restloginProgressiveProfileCallback
[warn] responseTimePercentiles was not found in /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/gatling/req_restloginprogre-9fc93.html - cannot check for spikes
no spikes found
________________________________________________________________________________
[2024-06-23 11:08:26] Reporting-login-tree-330 step14 : Collect Gatling simulation data
________________________________________________________________________________
collected stats :
{
"global": {
"name": "All Requests",
"numberOfRequests": {
"total": 4881696,
"ok": 4682546,
"ko": 199150
},
"minResponseTime": {
"total": 3,
"ok": 40,
"ko": 3
},
"maxResponseTime": {
"total": 60021,
"ok": 59871,
"ko": 60021
},
"meanResponseTime": {
"total": 5510,
"ok": 5659,
"ko": 2014
},
"standardDeviation": {
"total": 4979,
"ok": 4957,
"ko": 4157
},
"percentiles1": {
"total": 4726,
"ok": 4929,
"ko": 39
},
"percentiles2": {
"total": 8711,
"ok": 8835,
"ko": 1512
},
"percentiles3": {
"total": 14748,
"ok": 14856,
"ko": 11894
},
"percentiles4": {
"total": 19377,
"ok": 19451,
"ko": 16042
},
"group1": {
"name": "t < 800 ms",
"htmlName": "t < 800 ms",
"count": 1103574,
"percentage": 23
},
"group2": {
"name": "800 ms <= t < 1200 ms",
"htmlName": "t >= 800 ms
t < 1200 ms",
"count": 125673,
"percentage": 3
},
"group3": {
"name": "t >= 1200 ms",
"htmlName": "t >= 1200 ms",
"count": 3453299,
"percentage": 71
},
"group4": {
"name": "failed",
"htmlName": "failed",
"count": 199150,
"percentage": 4
},
"meanNumberOfRequestsPerSecond": {
"total": 1316.8858915565147,
"ok": 1263.1632047477744,
"ko": 53.72268680874022
}
},
"details": [
{
"name": "protectLoginInitiate",
"numberOfRequests": {
"total": 1192827,
"ok": 1160528,
"ko": 32299
},
"minResponseTime": {
"total": 3,
"ok": 43,
"ko": 3
},
"maxResponseTime": {
"total": 37805,
"ok": 37805,
"ko": 31598
},
"meanResponseTime": {
"total": 4439,
"ok": 4484,
"ko": 2807
},
"standardDeviation": {
"total": 4270,
"ok": 4258,
"ko": 4359
},
"percentiles1": {
"total": 3715,
"ok": 3768,
"ko": 41
},
"percentiles2": {
"total": 7249,
"ok": 7266,
"ko": 4043
},
"percentiles3": {
"total": 12116,
"ok": 12148,
"ko": 12044
},
"percentiles4": {
"total": 16656,
"ok": 16725,
"ko": 16046
},
"group1": {
"name": "t < 800 ms",
"htmlName": "t < 800 ms",
"count": 349611,
"percentage": 29
},
"group2": {
"name": "800 ms <= t < 1200 ms",
"htmlName": "t >= 800 ms
t < 1200 ms",
"count": 28667,
"percentage": 2
},
"group3": {
"name": "t >= 1200 ms",
"htmlName": "t >= 1200 ms",
"count": 782250,
"percentage": 66
},
"group4": {
"name": "failed",
"htmlName": "failed",
"count": 32299,
"percentage": 3
},
"meanNumberOfRequestsPerSecond": {
"total": 321.7769085513893,
"ok": 313.06393309954143,
"ko": 8.712975451847855
},
"spikes": []
},
{
"name": "PingOneProtectInitializeCallback",
"numberOfRequests": {
"total": 1160528,
"ok": 1137485,
"ko": 23043
},
"minResponseTime": {
"total": 32,
"ok": 40,
"ko": 32
},
"maxResponseTime": {
"total": 60021,
"ok": 59242,
"ko": 60021
},
"meanResponseTime": {
"total": 4315,
"ok": 4341,
"ko": 3051
},
"standardDeviation": {
"total": 4182,
"ok": 4156,
"ko": 5170
},
"percentiles1": {
"total": 3581,
"ok": 3625,
"ko": 39
},
"percentiles2": {
"total": 7094,
"ok": 7104,
"ko": 4041
},
"percentiles3": {
"total": 11975,
"ok": 11941,
"ko": 12042
},
"percentiles4": {
"total": 16139,
"ok": 16140,
"ko": 16156
},
"group1": {
"name": "t < 800 ms",
"htmlName": "t < 800 ms",
"count": 356683,
"percentage": 31
},
"group2": {
"name": "800 ms <= t < 1200 ms",
"htmlName": "t >= 800 ms
t < 1200 ms",
"count": 29550,
"percentage": 3
},
"group3": {
"name": "t >= 1200 ms",
"htmlName": "t >= 1200 ms",
"count": 751252,
"percentage": 65
},
"group4": {
"name": "failed",
"htmlName": "failed",
"count": 23043,
"percentage": 2
},
"meanNumberOfRequestsPerSecond": {
"total": 313.06393309954143,
"ok": 306.84785540868626,
"ko": 6.216077690855139
},
"spikes": []
},
{
"name": "restLoginUsernamePasswordCallback",
"numberOfRequests": {
"total": 1160528,
"ok": 1123927,
"ko": 36601
},
"minResponseTime": {
"total": 32,
"ok": 43,
"ko": 32
},
"maxResponseTime": {
"total": 60004,
"ok": 53072,
"ko": 60004
},
"meanResponseTime": {
"total": 5334,
"ok": 5439,
"ko": 2104
},
"standardDeviation": {
"total": 4567,
"ok": 4529,
"ko": 4546
},
"percentiles1": {
"total": 4646,
"ok": 4782,
"ko": 38
},
"percentiles2": {
"total": 8391,
"ok": 8480,
"ko": 1899
},
"percentiles3": {
"total": 13614,
"ok": 13650,
"ko": 12039
},
"percentiles4": {
"total": 17863,
"ok": 17901,
"ko": 16046
},
"group1": {
"name": "t < 800 ms",
"htmlName": "t < 800 ms",
"count": 222228,
"percentage": 19
},
"group2": {
"name": "800 ms <= t < 1200 ms",
"htmlName": "t >= 800 ms
t < 1200 ms",
"count": 38636,
"percentage": 3
},
"group3": {
"name": "t >= 1200 ms",
"htmlName": "t >= 1200 ms",
"count": 863063,
"percentage": 74
},
"group4": {
"name": "failed",
"htmlName": "failed",
"count": 36601,
"percentage": 3
},
"meanNumberOfRequestsPerSecond": {
"total": 313.06393309954143,
"ok": 303.1904504990558,
"ko": 9.873482600485568
},
"spikes": []
},
{
"name": "PingOneProtectEvaluationCallback",
"numberOfRequests": {
"total": 1160528,
"ok": 1110029,
"ko": 50499
},
"minResponseTime": {
"total": 32,
"ok": 43,
"ko": 32
},
"maxResponseTime": {
"total": 60009,
"ok": 59871,
"ko": 60009
},
"meanResponseTime": {
"total": 7850,
"ok": 8122,
"ko": 1867
},
"standardDeviation": {
"total": 5729,
"ok": 5642,
"ko": 4146
},
"percentiles1": {
"total": 7595,
"ok": 7823,
"ko": 38
},
"percentiles2": {
"total": 11663,
"ok": 11844,
"ko": 52
},
"percentiles3": {
"total": 17857,
"ok": 17956,
"ko": 11371
},
"percentiles4": {
"total": 22505,
"ok": 22708,
"ko": 16040
},
"group1": {
"name": "t < 800 ms",
"htmlName": "t < 800 ms",
"count": 154195,
"percentage": 13
},
"group2": {
"name": "800 ms <= t < 1200 ms",
"htmlName": "t >= 800 ms
t < 1200 ms",
"count": 26160,
"percentage": 2
},
"group3": {
"name": "t >= 1200 ms",
"htmlName": "t >= 1200 ms",
"count": 929674,
"percentage": 80
},
"group4": {
"name": "failed",
"htmlName": "failed",
"count": 50499,
"percentage": 4
},
"meanNumberOfRequestsPerSecond": {
"total": 313.06393309954143,
"ok": 299.4413272187753,
"ko": 13.622605880766118
},
"spikes": []
},
{
"name": "restloginProgressiveProfileCallback",
"numberOfRequests": {
"total": 207285,
"ok": 150577,
"ko": 56708
},
"minResponseTime": {
"total": 32,
"ok": 130,
"ko": 32
},
"maxResponseTime": {
"total": 60000,
"ok": 52771,
"ko": 60000
},
"meanResponseTime": {
"total": 6246,
"ok": 8142,
"ko": 1213
},
"standardDeviation": {
"total": 5879,
"ok": 5577,
"ko": 2981
},
"percentiles1": {
"total": 5607,
"ok": 7825,
"ko": 38
},
"percentiles2": {
"total": 10300,
"ok": 11880,
"ko": 53
},
"percentiles3": {
"total": 16895,
"ok": 17699,
"ko": 9037
},
"percentiles4": {
"total": 21554,
"ok": 23129,
"ko": 12802
},
"group1": {
"name": "t < 800 ms",
"htmlName": "t < 800 ms",
"count": 20857,
"percentage": 10
},
"group2": {
"name": "800 ms <= t < 1200 ms",
"htmlName": "t >= 800 ms
t < 1200 ms",
"count": 2660,
"percentage": 1
},
"group3": {
"name": "t >= 1200 ms",
"htmlName": "t >= 1200 ms",
"count": 127060,
"percentage": 61
},
"group4": {
"name": "failed",
"htmlName": "failed",
"count": 56708,
"percentage": 27
},
"meanNumberOfRequestsPerSecond": {
"total": 55.91718370650121,
"ok": 40.61963852171567,
"ko": 15.297545184785541
},
"spikes": []
}
]
}
{
"concurrency": null,
"generic_stats": {
"avg_num_of_requests_per_second": 1317,
"avg_response_time": 5510,
"max_response_time": 59871,
"min_response_time": 40,
"num_requests": 4881696,
"num_requests_pass": 4682546,
"num_requests_percent_pass": 95.92
},
"options": "; testCase:idc.loginProtect; testMode:open; throughput:330; rampup-duration:30; duration:3600",
"stats": {
"details": [
{
"group1": {
"count": 349611,
"htmlName": "t < 800 ms",
"name": "t < 800 ms",
"percentage": 29
},
"group2": {
"count": 28667,
"htmlName": "t >= 800 ms
t < 1200 ms",
"name": "800 ms <= t < 1200 ms",
"percentage": 2
},
"group3": {
"count": 782250,
"htmlName": "t >= 1200 ms",
"name": "t >= 1200 ms",
"percentage": 66
},
"group4": {
"count": 32299,
"htmlName": "failed",
"name": "failed",
"percentage": 3
},
"maxResponseTime": {
"ko": 31598,
"ok": 37805,
"total": 37805
},
"meanNumberOfRequestsPerSecond": {
"ko": 8.712975451847855,
"ok": 313.06393309954143,
"total": 321.7769085513893
},
"meanResponseTime": {
"ko": 2807,
"ok": 4484,
"total": 4439
},
"minResponseTime": {
"ko": 3,
"ok": 43,
"total": 3
},
"name": "protectLoginInitiate",
"numberOfRequests": {
"ko": 32299,
"ok": 1160528,
"total": 1192827
},
"percentiles1": {
"ko": 41,
"ok": 3768,
"total": 3715
},
"percentiles2": {
"ko": 4043,
"ok": 7266,
"total": 7249
},
"percentiles3": {
"ko": 12044,
"ok": 12148,
"total": 12116
},
"percentiles4": {
"ko": 16046,
"ok": 16725,
"total": 16656
},
"spikes": [],
"standardDeviation": {
"ko": 4359,
"ok": 4258,
"total": 4270
}
},
{
"group1": {
"count": 356683,
"htmlName": "t < 800 ms",
"name": "t < 800 ms",
"percentage": 31
},
"group2": {
"count": 29550,
"htmlName": "t >= 800 ms
t < 1200 ms",
"name": "800 ms <= t < 1200 ms",
"percentage": 3
},
"group3": {
"count": 751252,
"htmlName": "t >= 1200 ms",
"name": "t >= 1200 ms",
"percentage": 65
},
"group4": {
"count": 23043,
"htmlName": "failed",
"name": "failed",
"percentage": 2
},
"maxResponseTime": {
"ko": 60021,
"ok": 59242,
"total": 60021
},
"meanNumberOfRequestsPerSecond": {
"ko": 6.216077690855139,
"ok": 306.84785540868626,
"total": 313.06393309954143
},
"meanResponseTime": {
"ko": 3051,
"ok": 4341,
"total": 4315
},
"minResponseTime": {
"ko": 32,
"ok": 40,
"total": 32
},
"name": "PingOneProtectInitializeCallback",
"numberOfRequests": {
"ko": 23043,
"ok": 1137485,
"total": 1160528
},
"percentiles1": {
"ko": 39,
"ok": 3625,
"total": 3581
},
"percentiles2": {
"ko": 4041,
"ok": 7104,
"total": 7094
},
"percentiles3": {
"ko": 12042,
"ok": 11941,
"total": 11975
},
"percentiles4": {
"ko": 16156,
"ok": 16140,
"total": 16139
},
"spikes": [],
"standardDeviation": {
"ko": 5170,
"ok": 4156,
"total": 4182
}
},
{
"group1": {
"count": 222228,
"htmlName": "t < 800 ms",
"name": "t < 800 ms",
"percentage": 19
},
"group2": {
"count": 38636,
"htmlName": "t >= 800 ms
t < 1200 ms",
"name": "800 ms <= t < 1200 ms",
"percentage": 3
},
"group3": {
"count": 863063,
"htmlName": "t >= 1200 ms",
"name": "t >= 1200 ms",
"percentage": 74
},
"group4": {
"count": 36601,
"htmlName": "failed",
"name": "failed",
"percentage": 3
},
"maxResponseTime": {
"ko": 60004,
"ok": 53072,
"total": 60004
},
"meanNumberOfRequestsPerSecond": {
"ko": 9.873482600485568,
"ok": 303.1904504990558,
"total": 313.06393309954143
},
"meanResponseTime": {
"ko": 2104,
"ok": 5439,
"total": 5334
},
"minResponseTime": {
"ko": 32,
"ok": 43,
"total": 32
},
"name": "restLoginUsernamePasswordCallback",
"numberOfRequests": {
"ko": 36601,
"ok": 1123927,
"total": 1160528
},
"percentiles1": {
"ko": 38,
"ok": 4782,
"total": 4646
},
"percentiles2": {
"ko": 1899,
"ok": 8480,
"total": 8391
},
"percentiles3": {
"ko": 12039,
"ok": 13650,
"total": 13614
},
"percentiles4": {
"ko": 16046,
"ok": 17901,
"total": 17863
},
"spikes": [],
"standardDeviation": {
"ko": 4546,
"ok": 4529,
"total": 4567
}
},
{
"group1": {
"count": 154195,
"htmlName": "t < 800 ms",
"name": "t < 800 ms",
"percentage": 13
},
"group2": {
"count": 26160,
"htmlName": "t >= 800 ms
t < 1200 ms",
"name": "800 ms <= t < 1200 ms",
"percentage": 2
},
"group3": {
"count": 929674,
"htmlName": "t >= 1200 ms",
"name": "t >= 1200 ms",
"percentage": 80
},
"group4": {
"count": 50499,
"htmlName": "failed",
"name": "failed",
"percentage": 4
},
"maxResponseTime": {
"ko": 60009,
"ok": 59871,
"total": 60009
},
"meanNumberOfRequestsPerSecond": {
"ko": 13.622605880766118,
"ok": 299.4413272187753,
"total": 313.06393309954143
},
"meanResponseTime": {
"ko": 1867,
"ok": 8122,
"total": 7850
},
"minResponseTime": {
"ko": 32,
"ok": 43,
"total": 32
},
"name": "PingOneProtectEvaluationCallback",
"numberOfRequests": {
"ko": 50499,
"ok": 1110029,
"total": 1160528
},
"percentiles1": {
"ko": 38,
"ok": 7823,
"total": 7595
},
"percentiles2": {
"ko": 52,
"ok": 11844,
"total": 11663
},
"percentiles3": {
"ko": 11371,
"ok": 17956,
"total": 17857
},
"percentiles4": {
"ko": 16040,
"ok": 22708,
"total": 22505
},
"spikes": [],
"standardDeviation": {
"ko": 4146,
"ok": 5642,
"total": 5729
}
},
{
"group1": {
"count": 20857,
"htmlName": "t < 800 ms",
"name": "t < 800 ms",
"percentage": 10
},
"group2": {
"count": 2660,
"htmlName": "t >= 800 ms
t < 1200 ms",
"name": "800 ms <= t < 1200 ms",
"percentage": 1
},
"group3": {
"count": 127060,
"htmlName": "t >= 1200 ms",
"name": "t >= 1200 ms",
"percentage": 61
},
"group4": {
"count": 56708,
"htmlName": "failed",
"name": "failed",
"percentage": 27
},
"maxResponseTime": {
"ko": 60000,
"ok": 52771,
"total": 60000
},
"meanNumberOfRequestsPerSecond": {
"ko": 15.297545184785541,
"ok": 40.61963852171567,
"total": 55.91718370650121
},
"meanResponseTime": {
"ko": 1213,
"ok": 8142,
"total": 6246
},
"minResponseTime": {
"ko": 32,
"ok": 130,
"total": 32
},
"name": "restloginProgressiveProfileCallback",
"numberOfRequests": {
"ko": 56708,
"ok": 150577,
"total": 207285
},
"percentiles1": {
"ko": 38,
"ok": 7825,
"total": 5607
},
"percentiles2": {
"ko": 53,
"ok": 11880,
"total": 10300
},
"percentiles3": {
"ko": 9037,
"ok": 17699,
"total": 16895
},
"percentiles4": {
"ko": 12802,
"ok": 23129,
"total": 21554
},
"spikes": [],
"standardDeviation": {
"ko": 2981,
"ok": 5577,
"total": 5879
}
}
],
"global": {
"group1": {
"count": 1103574,
"htmlName": "t < 800 ms",
"name": "t < 800 ms",
"percentage": 23
},
"group2": {
"count": 125673,
"htmlName": "t >= 800 ms
t < 1200 ms",
"name": "800 ms <= t < 1200 ms",
"percentage": 3
},
"group3": {
"count": 3453299,
"htmlName": "t >= 1200 ms",
"name": "t >= 1200 ms",
"percentage": 71
},
"group4": {
"count": 199150,
"htmlName": "failed",
"name": "failed",
"percentage": 4
},
"maxResponseTime": {
"ko": 60021,
"ok": 59871,
"total": 60021
},
"meanNumberOfRequestsPerSecond": {
"ko": 53.72268680874022,
"ok": 1263.1632047477744,
"total": 1316.8858915565147
},
"meanResponseTime": {
"ko": 2014,
"ok": 5659,
"total": 5510
},
"minResponseTime": {
"ko": 3,
"ok": 40,
"total": 3
},
"name": "All Requests",
"numberOfRequests": {
"ko": 199150,
"ok": 4682546,
"total": 4881696
},
"percentiles1": {
"ko": 39,
"ok": 4929,
"total": 4726
},
"percentiles2": {
"ko": 1512,
"ok": 8835,
"total": 8711
},
"percentiles3": {
"ko": 11894,
"ok": 14856,
"total": 14748
},
"percentiles4": {
"ko": 16042,
"ok": 19451,
"total": 19377
},
"standardDeviation": {
"ko": 4157,
"ok": 4957,
"total": 4979
}
}
},
"target_hostname": "openam-ema-perf-p1.forgeblocks.com",
"tool_name": "gatling"
}
________________________________________________________________________________
[2024-06-23 11:08:26] Reporting-login-tree-330 step15 : Update run description in the Gatling HTML report
________________________________________________________________________________
________________________________________________________________________________
[2024-06-23 11:08:26] Reporting-login-tree-330 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/07_login-tree-330/stdout.txt
Compressing Gatling stdout.txt file..
________________________________________________________________________________
[2024-06-23 11:09:06] Reporting-login-tree-330 post : Check output
________________________________________________________________________________
Setting result to PASS
Task has been successfully stopped