--Task--
name: Reporting-authn-users-5000
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-authn-users-100', 'Reporting-authn-users-1000', 'Reporting-authn-users-3000']
preceding_task: None
options: {'task_name': 'authn-users-5000'}
group_name: None
Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock
________________________________________________________________________________
[2024-04-15 00:10:11] Reporting-authn-users-5000 pre : N/A
________________________________________________________________________________
source (overseer-0) is a component
[2024-04-15 00:10:11] - INFO: Timeout for this Task has changed to 3h 30m (12600 seconds)
[2024-04-15 00:10:11] - 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-authn-users-5000/stdout.txt
Pod stderr file : /results/orders/Reporting-authn-users-5000/stderr.txt
Pod log : /results/orders/Reporting-authn-users-5000
________________________________________________________________________________
[2024-04-15 00:10:11] Reporting-authn-users-5000 step1 : Write order for overseer to generate the report
________________________________________________________________________________
2024-04-15 00:10:11,263 INFO
2024-04-15 00:10:11,263 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:10:11,263 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:10:11,586 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:10:11,586 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:10:11,586 DEBUG --- stdout ---
2024-04-15 00:10:11,586 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:10:11,586 DEBUG --- stderr ---
2024-04-15 00:10:11,586 DEBUG
Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/08_Verification/12_Reporting-authn-users-5000/order.json
{
"name": "Reporting-authn-users-5000",
"command": "/lodestar/clients/gatling-report-only-for-pyrock.sh authn-users-5000 --extra-run-jvm-options \"-Xmx4G\"",
"out-dir": "/results/orders/Reporting-authn-users-5000"
}
________________________________________________________________________________
[2024-04-15 00:10:11] Reporting-authn-users-5000 step2 : Make sure overseer is ready to accept order (not already busy)
________________________________________________________________________________
2024-04-15 00:10:11,587 INFO
2024-04-15 00:10:11,588 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING"
2024-04-15 00:10:11,588 INFO [loop_until]: (max_time=120, interval=5, expected_rc=[0]
2024-04-15 00:10:11,825 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-04-15 00:10:11,825 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:10:11,825 DEBUG --- stdout ---
2024-04-15 00:10:11,825 DEBUG WAITING
2024-04-15 00:10:11,825 DEBUG --- stderr ---
2024-04-15 00:10:11,825 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current
Dload Upload Total Spent Left Speed
0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0
100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33
________________________________________________________________________________
[2024-04-15 00:10:11] Reporting-authn-users-5000 step3 : Send order to overseer
________________________________________________________________________________
2024-04-15 00:10:11,826 INFO
2024-04-15 00:10:11,826 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:10:11,826 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:10:12,132 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:10:12,132 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:10:12,132 DEBUG --- stdout ---
2024-04-15 00:10:12,132 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:10:12,132 DEBUG --- stderr ---
2024-04-15 00:10:12,132 DEBUG
2024-04-15 00:10:12,133 INFO
2024-04-15 00:10:12,133 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/08_Verification/12_Reporting-authn-users-5000/order.json overseer-0-5584f6c4db-bcn5q:/results/orders/order.json
2024-04-15 00:10:12,133 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-04-15 00:10:13,513 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:10:13,513 DEBUG --- stdout ---
2024-04-15 00:10:13,513 DEBUG
2024-04-15 00:10:13,513 DEBUG --- stderr ---
2024-04-15 00:10:13,513 DEBUG
Order has been successfully forwarded to overseer
________________________________________________________________________________
[2024-04-15 00:10:13] Reporting-authn-users-5000 step4 : Wait order is done
________________________________________________________________________________
Workaround for PERF-1966. Sleeping for 5 seconds.
2024-04-15 00:10:18,519 INFO
2024-04-15 00:10:18,520 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING"
2024-04-15 00:10:18,520 INFO [loop_until]: (max_time=12600, interval=5, expected_rc=[0]
2024-04-15 00:10:18,767 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:10:24,010 INFO [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:10:29,261 INFO [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:10:34,508 INFO [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:10:39,762 INFO [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:10:45,018 INFO [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:10:50,259 INFO [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:10:55,575 INFO [loop_until]: Function succeeded after 37s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:00,827 INFO [loop_until]: Function succeeded after 42s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:06,078 INFO [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:11,321 INFO [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:16,571 INFO [loop_until]: Function succeeded after 58s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:21,815 INFO [loop_until]: Function succeeded after 1m 03s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:27,121 INFO [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:32,367 INFO [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:37,640 INFO [loop_until]: Function succeeded after 1m 19s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:42,903 INFO [loop_until]: Function succeeded after 1m 24s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:48,155 INFO [loop_until]: Function succeeded after 1m 29s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:53,406 INFO [loop_until]: Function succeeded after 1m 34s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:11:58,707 INFO [loop_until]: Function succeeded after 1m 40s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:12:03,958 INFO [loop_until]: Function succeeded after 1m 45s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:12:09,207 INFO [loop_until]: Function succeeded after 1m 50s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:12:14,464 INFO [loop_until]: Function succeeded after 1m 55s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:12:19,713 INFO [loop_until]: Function succeeded after 2m 01s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:12:24,963 INFO [loop_until]: Function succeeded after 2m 06s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:12:30,261 INFO [loop_until]: Function succeeded after 2m 11s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:12:35,547 INFO [loop_until]: Function succeeded after 2m 17s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:12:40,790 INFO [loop_until]: Function succeeded after 2m 22s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:12:46,043 INFO [loop_until]: Function succeeded after 2m 27s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:12:51,285 INFO [loop_until]: Function succeeded after 2m 32s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:12:56,538 INFO [loop_until]: Function succeeded after 2m 38s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:01,849 INFO [loop_until]: Function succeeded after 2m 43s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:07,107 INFO [loop_until]: Function succeeded after 2m 48s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:12,360 INFO [loop_until]: Function succeeded after 2m 53s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:17,616 INFO [loop_until]: Function succeeded after 2m 59s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:22,860 INFO [loop_until]: Function succeeded after 3m 04s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:28,106 INFO [loop_until]: Function succeeded after 3m 09s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:33,396 INFO [loop_until]: Function succeeded after 3m 14s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:38,654 INFO [loop_until]: Function succeeded after 3m 20s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:43,896 INFO [loop_until]: Function succeeded after 3m 25s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:49,137 INFO [loop_until]: Function succeeded after 3m 30s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:54,376 INFO [loop_until]: Function succeeded after 3m 35s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:13:59,628 INFO [loop_until]: Function succeeded after 3m 41s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:14:04,869 INFO [loop_until]: Function succeeded after 3m 46s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:14:10,186 INFO [loop_until]: Function succeeded after 3m 51s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:14:15,443 INFO [loop_until]: Function succeeded after 3m 56s (rc=0) - failed to find expected output: WAITING - retry
2024-04-15 00:14:20,691 INFO [loop_until]: Function succeeded after 4m 02s (rc=0) - expected pattern found
2024-04-15 00:14:20,692 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:20,692 DEBUG --- stdout ---
2024-04-15 00:14:20,692 DEBUG WAITING
2024-04-15 00:14:20,692 DEBUG --- stderr ---
2024-04-15 00:14:20,692 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current
Dload Upload Total Spent Left Speed
0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0
100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32
________________________________________________________________________________
[2024-04-15 00:14:20] Reporting-authn-users-5000 step5 : Wait until gatling report is generated on overseer
________________________________________________________________________________
2024-04-15 00:14:20,693 INFO
2024-04-15 00:14:20,693 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:20,693 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:21,043 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:21,043 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:21,043 DEBUG --- stdout ---
2024-04-15 00:14:21,043 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:21,043 DEBUG --- stderr ---
2024-04-15 00:14:21,043 DEBUG
Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait.
2024-04-15 00:14:21,044 INFO
2024-04-15 00:14:21,044 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:21,044 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:21,404 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:21,404 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:21,404 DEBUG --- stdout ---
2024-04-15 00:14:21,404 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:21,404 DEBUG --- stderr ---
2024-04-15 00:14:21,405 DEBUG
2024-04-15 00:14:21,405 INFO
2024-04-15 00:14:21,405 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/gatling/authn-users-5000.tar.gz" ]; then echo "/results/gatling/authn-users-5000.tar.gz file found"; exit 0; else echo "/results/gatling/authn-users-5000.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/authn-users-5000.tar.gz file found"
2024-04-15 00:14:21,405 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-04-15 00:14:22,365 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-04-15 00:14:22,365 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:22,365 DEBUG --- stdout ---
2024-04-15 00:14:22,365 DEBUG /results/gatling/authn-users-5000.tar.gz file found
2024-04-15 00:14:22,365 DEBUG --- stderr ---
2024-04-15 00:14:22,365 DEBUG
2024-04-15 00:14:22,366 INFO
2024-04-15 00:14:22,366 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:22,366 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:22,704 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:22,705 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:22,705 DEBUG --- stdout ---
2024-04-15 00:14:22,705 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:22,705 DEBUG --- stderr ---
2024-04-15 00:14:22,705 DEBUG
2024-04-15 00:14:22,705 INFO
2024-04-15 00:14:22,705 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/gatling/authn-users-5000.tar.gz
2024-04-15 00:14:23,563 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-04-15 00:14:23,563 DEBUG --- stdout ---
2024-04-15 00:14:23,563 DEBUG 224 /results/gatling/authn-users-5000.tar.gz
2024-04-15 00:14:23,563 DEBUG --- stderr ---
2024-04-15 00:14:23,563 DEBUG
2024-04-15 00:14:23,563 INFO
- Download file (size 224k)
2024-04-15 00:14:23,563 INFO
2024-04-15 00:14:23,563 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:23,563 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:23,896 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:23,896 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:23,896 DEBUG --- stdout ---
2024-04-15 00:14:23,896 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:23,896 DEBUG --- stderr ---
2024-04-15 00:14:23,896 DEBUG
source : /results/gatling/authn-users-5000.tar.gz @ overseer-0-5584f6c4db-bcn5q
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/shared/authn-users-5000.tar.gz
2024-04-15 00:14:23,897 INFO
2024-04-15 00:14:23,897 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:23,897 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:24,232 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:24,232 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:24,232 DEBUG --- stdout ---
2024-04-15 00:14:24,232 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:24,232 DEBUG --- stderr ---
2024-04-15 00:14:24,232 DEBUG
2024-04-15 00:14:24,233 INFO
2024-04-15 00:14:24,233 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/gatling/authn-users-5000.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/shared/authn-users-5000.tar.gz
2024-04-15 00:14:24,233 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-04-15 00:14:25,224 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:25,224 DEBUG --- stdout ---
2024-04-15 00:14:25,224 DEBUG tar: Removing leading `/' from member names
2024-04-15 00:14:25,224 DEBUG --- stderr ---
2024-04-15 00:14:25,224 DEBUG
________________________________________________________________________________
[2024-04-15 00:14:25] Reporting-authn-users-5000 step6 : Unzip the result
________________________________________________________________________________
2024-04-15 00:14:25,225 INFO
2024-04-15 00:14:25,225 INFO [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/shared/authn-users-5000.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/gatling
2024-04-15 00:14:25,263 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-04-15 00:14:25,263 DEBUG --- stdout ---
2024-04-15 00:14:25,263 DEBUG
2024-04-15 00:14:25,263 DEBUG --- stderr ---
2024-04-15 00:14:25,263 DEBUG
2024-04-15 00:14:25,263 INFO
________________________________________________________________________________
[2024-04-15 00:14:25] Reporting-authn-users-5000 step7 : Print rc order
________________________________________________________________________________
2024-04-15 00:14:25,264 INFO
2024-04-15 00:14:25,265 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:25,265 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:25,577 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:25,577 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:25,577 DEBUG --- stdout ---
2024-04-15 00:14:25,577 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:25,577 DEBUG --- stderr ---
2024-04-15 00:14:25,577 DEBUG
Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait.
2024-04-15 00:14:25,578 INFO
2024-04-15 00:14:25,578 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:25,578 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:25,885 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:25,887 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:25,887 DEBUG --- stdout ---
2024-04-15 00:14:25,887 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:25,887 DEBUG --- stderr ---
2024-04-15 00:14:25,887 DEBUG
2024-04-15 00:14:25,888 INFO
2024-04-15 00:14:25,888 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/Reporting-authn-users-5000/rc.txt" ]; then echo "/results/orders/Reporting-authn-users-5000/rc.txt file found"; exit 0; else echo "/results/orders/Reporting-authn-users-5000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-authn-users-5000/rc.txt file found"
2024-04-15 00:14:25,889 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-04-15 00:14:26,770 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-04-15 00:14:26,770 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:26,770 DEBUG --- stdout ---
2024-04-15 00:14:26,770 DEBUG /results/orders/Reporting-authn-users-5000/rc.txt file found
2024-04-15 00:14:26,770 DEBUG --- stderr ---
2024-04-15 00:14:26,771 DEBUG
2024-04-15 00:14:26,771 INFO
2024-04-15 00:14:26,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-04-15 00:14:26,771 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:27,085 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:27,085 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:27,085 DEBUG --- stdout ---
2024-04-15 00:14:27,085 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:27,085 DEBUG --- stderr ---
2024-04-15 00:14:27,085 DEBUG
2024-04-15 00:14:27,085 INFO
2024-04-15 00:14:27,085 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/Reporting-authn-users-5000/rc.txt
2024-04-15 00:14:27,941 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-04-15 00:14:27,941 DEBUG --- stdout ---
2024-04-15 00:14:27,941 DEBUG 4 /results/orders/Reporting-authn-users-5000/rc.txt
2024-04-15 00:14:27,941 DEBUG --- stderr ---
2024-04-15 00:14:27,941 DEBUG
2024-04-15 00:14:27,941 INFO
- Download file (size 4k)
2024-04-15 00:14:27,942 INFO
2024-04-15 00:14:27,942 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:27,942 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:28,281 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:28,282 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:28,282 DEBUG --- stdout ---
2024-04-15 00:14:28,282 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:28,282 DEBUG --- stderr ---
2024-04-15 00:14:28,282 DEBUG
source : /results/orders/Reporting-authn-users-5000/rc.txt @ overseer-0-5584f6c4db-bcn5q
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/08_Verification/12_Reporting-authn-users-5000/rc.txt
2024-04-15 00:14:28,282 INFO
2024-04-15 00:14:28,283 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:28,283 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:28,613 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:28,614 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:28,614 DEBUG --- stdout ---
2024-04-15 00:14:28,614 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:28,614 DEBUG --- stderr ---
2024-04-15 00:14:28,614 DEBUG
2024-04-15 00:14:28,614 INFO
2024-04-15 00:14:28,614 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/Reporting-authn-users-5000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/08_Verification/12_Reporting-authn-users-5000/rc.txt
2024-04-15 00:14:28,614 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-04-15 00:14:29,399 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:29,399 DEBUG --- stdout ---
2024-04-15 00:14:29,399 DEBUG tar: Removing leading `/' from member names
2024-04-15 00:14:29,399 DEBUG --- stderr ---
2024-04-15 00:14:29,399 DEBUG
2024-04-15 00:14:29,399 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/08_Verification/12_Reporting-authn-users-5000/rc.txt is small (1 lines), printing all lines:
2024-04-15 00:14:29,399 DEBUG ----- output -----
2024-04-15 00:14:29,399 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15])
2024-04-15 00:14:29,400 INFO
________________________________________________________________________________
[2024-04-15 00:14:29] Reporting-authn-users-5000 step8 : Check rc log
________________________________________________________________________________
PASS: no error found in rc log
________________________________________________________________________________
[2024-04-15 00:14:29] Reporting-authn-users-5000 step9 : Print stdout order
________________________________________________________________________________
2024-04-15 00:14:29,401 INFO
2024-04-15 00:14:29,401 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:29,401 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:29,708 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:29,708 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:29,708 DEBUG --- stdout ---
2024-04-15 00:14:29,708 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:29,708 DEBUG --- stderr ---
2024-04-15 00:14:29,708 DEBUG
Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait.
2024-04-15 00:14:29,708 INFO
2024-04-15 00:14:29,708 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:29,709 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:30,016 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:30,016 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:30,016 DEBUG --- stdout ---
2024-04-15 00:14:30,016 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:30,017 DEBUG --- stderr ---
2024-04-15 00:14:30,017 DEBUG
2024-04-15 00:14:30,017 INFO
2024-04-15 00:14:30,017 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/Reporting-authn-users-5000/stdout.txt" ]; then echo "/results/orders/Reporting-authn-users-5000/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting-authn-users-5000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-authn-users-5000/stdout.txt file found"
2024-04-15 00:14:30,017 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-04-15 00:14:30,912 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-04-15 00:14:30,914 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:30,915 DEBUG --- stdout ---
2024-04-15 00:14:30,915 DEBUG /results/orders/Reporting-authn-users-5000/stdout.txt file found
2024-04-15 00:14:30,915 DEBUG --- stderr ---
2024-04-15 00:14:30,915 DEBUG
2024-04-15 00:14:30,915 INFO
2024-04-15 00:14:30,915 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:30,915 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:31,262 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:31,263 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:31,263 DEBUG --- stdout ---
2024-04-15 00:14:31,263 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:31,263 DEBUG --- stderr ---
2024-04-15 00:14:31,263 DEBUG
2024-04-15 00:14:31,263 INFO
2024-04-15 00:14:31,263 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/Reporting-authn-users-5000/stdout.txt
2024-04-15 00:14:32,109 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-04-15 00:14:32,110 DEBUG --- stdout ---
2024-04-15 00:14:32,110 DEBUG 4 /results/orders/Reporting-authn-users-5000/stdout.txt
2024-04-15 00:14:32,110 DEBUG --- stderr ---
2024-04-15 00:14:32,110 DEBUG
2024-04-15 00:14:32,110 INFO
- Download file (size 4k)
2024-04-15 00:14:32,110 INFO
2024-04-15 00:14:32,110 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:32,110 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:32,426 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:32,426 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:32,426 DEBUG --- stdout ---
2024-04-15 00:14:32,426 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:32,426 DEBUG --- stderr ---
2024-04-15 00:14:32,426 DEBUG
source : /results/orders/Reporting-authn-users-5000/stdout.txt @ overseer-0-5584f6c4db-bcn5q
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/08_Verification/12_Reporting-authn-users-5000/stdout.txt
2024-04-15 00:14:32,426 INFO
2024-04-15 00:14:32,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-04-15 00:14:32,427 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:32,740 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:32,741 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:32,741 DEBUG --- stdout ---
2024-04-15 00:14:32,741 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:32,741 DEBUG --- stderr ---
2024-04-15 00:14:32,741 DEBUG
2024-04-15 00:14:32,741 INFO
2024-04-15 00:14:32,741 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/Reporting-authn-users-5000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/08_Verification/12_Reporting-authn-users-5000/stdout.txt
2024-04-15 00:14:32,741 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-04-15 00:14:33,524 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:33,524 DEBUG --- stdout ---
2024-04-15 00:14:33,524 DEBUG tar: Removing leading `/' from member names
2024-04-15 00:14:33,524 DEBUG --- stderr ---
2024-04-15 00:14:33,524 DEBUG
2024-04-15 00:14:33,525 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/08_Verification/12_Reporting-authn-users-5000/stdout.txt is small (75 lines), printing all lines:
2024-04-15 00:14:33,525 DEBUG ----- output -----
2024-04-15 00:14:33,525 DEBUG [cmd] /lodestar/clients/gatling/bin/gatling.sh --reports-only /results/gatling/authn-users-5000/loadtest-20240414204413012 --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 18077268 (OK=18077264 KO=4 )
> min response time 4 (OK=8 KO=4 )
> max response time 1054 (OK=1054 KO=10 )
> mean response time 19 (OK=19 KO=6 )
> std deviation 15 (OK=15 KO=2 )
> response time 50th percentile 15 (OK=15 KO=6 )
> response time 75th percentile 20 (OK=20 KO=7 )
> response time 95th percentile 39 (OK=39 KO=9 )
> response time 99th percentile 74 (OK=74 KO=10 )
> mean requests/sec 4978.592 (OK=4978.591 KO=0.001 )
---- Response Time Distribution ------------------------------------------------
> t < 800 ms 18077255 (100%)
> 800 ms <= t < 1200 ms 9 ( 0%)
> t >= 1200 ms 0 ( 0%)
> failed 4 ( 0%)
---- Errors --------------------------------------------------------------------
> status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 4 (100.0%)
), but actually found 502
================================================================================
Reports generated in 0s.
Please open the following file: file:///results/gatling/authn-users-5000/loadtest-20240414204413012/index.html
----------------------
[15/Apr/2024 01:14:15] Create tar file /results/gatling/authn-users-5000.tar.gz
----------------------
SIMULATION_PATH=/results/gatling/authn-users-5000/loadtest-20240414204413012
RESULT_FOLDER=authn-users-5000
RESULT_PATH=/results/gatling/authn-users-5000
PWD=/results/gatling/authn-users-5000/loadtest-20240414204413012
[cmd] tar -czvf /results/gatling/authn-users-5000.tar.gz --exclude=simulation.log *
index.html
js/
js/jquery-3.5.1.min.js
js/menu.js
js/assertions.json
js/global_stats.json
js/bootstrap.min.js
js/theme.js
js/unpack.js
js/highstock.js
js/stats.json
js/stats.js
js/ellipsis.js
js/gatling.js
js/highcharts-more.js
js/all_sessions.js
js/assertions.xml
req_userrestlogin-03b82.html
style/
style/favicon.ico
style/sorted-up.png
style/sorted-down.png
style/arrow_right.png
style/style.css
style/little_arrow_right.png
style/sortable.png
style/arrow_right_black.png
style/stat-l-roue.png
style/logo.svg
style/arrow_down.png
style/stat-l-temps.png
style/stat-fleche-bas.png
style/logo-enterprise.svg
style/arrow_down_black.png
style/bootstrap.min.css
2024-04-15 00:14:33,525 INFO
________________________________________________________________________________
[2024-04-15 00:14:33] Reporting-authn-users-5000 step10 : Check stdout log
________________________________________________________________________________
PASS: no error found in stdout log
________________________________________________________________________________
[2024-04-15 00:14:33] Reporting-authn-users-5000 step11 : Print stderr order
________________________________________________________________________________
2024-04-15 00:14:33,526 INFO
2024-04-15 00:14:33,526 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:33,526 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:33,831 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:33,832 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:33,832 DEBUG --- stdout ---
2024-04-15 00:14:33,832 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:33,832 DEBUG --- stderr ---
2024-04-15 00:14:33,832 DEBUG
Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait.
2024-04-15 00:14:33,832 INFO
2024-04-15 00:14:33,832 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:33,832 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:34,139 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:34,139 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:34,139 DEBUG --- stdout ---
2024-04-15 00:14:34,139 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:34,139 DEBUG --- stderr ---
2024-04-15 00:14:34,139 DEBUG
2024-04-15 00:14:34,140 INFO
2024-04-15 00:14:34,140 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/Reporting-authn-users-5000/stderr.txt" ]; then echo "/results/orders/Reporting-authn-users-5000/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting-authn-users-5000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting-authn-users-5000/stderr.txt file found"
2024-04-15 00:14:34,140 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-04-15 00:14:34,969 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-04-15 00:14:34,970 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:34,970 DEBUG --- stdout ---
2024-04-15 00:14:34,970 DEBUG /results/orders/Reporting-authn-users-5000/stderr.txt file found
2024-04-15 00:14:34,970 DEBUG --- stderr ---
2024-04-15 00:14:34,970 DEBUG
2024-04-15 00:14:34,970 INFO
2024-04-15 00:14:34,970 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:34,970 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:35,286 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:35,286 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:35,286 DEBUG --- stdout ---
2024-04-15 00:14:35,286 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:35,286 DEBUG --- stderr ---
2024-04-15 00:14:35,286 DEBUG
2024-04-15 00:14:35,286 INFO
2024-04-15 00:14:35,286 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/Reporting-authn-users-5000/stderr.txt
2024-04-15 00:14:36,117 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-04-15 00:14:36,119 DEBUG --- stdout ---
2024-04-15 00:14:36,119 DEBUG 0 /results/orders/Reporting-authn-users-5000/stderr.txt
2024-04-15 00:14:36,119 DEBUG --- stderr ---
2024-04-15 00:14:36,119 DEBUG
2024-04-15 00:14:36,119 INFO
- Download file (size 0k)
2024-04-15 00:14:36,120 INFO
2024-04-15 00:14:36,120 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:36,120 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:36,456 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:36,457 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:36,457 DEBUG --- stdout ---
2024-04-15 00:14:36,457 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:36,457 DEBUG --- stderr ---
2024-04-15 00:14:36,457 DEBUG
source : /results/orders/Reporting-authn-users-5000/stderr.txt @ overseer-0-5584f6c4db-bcn5q
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/08_Verification/12_Reporting-authn-users-5000/stderr.txt
2024-04-15 00:14:36,457 INFO
2024-04-15 00:14:36,457 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-04-15 00:14:36,457 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-04-15 00:14:36,795 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-04-15 00:14:36,796 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:36,796 DEBUG --- stdout ---
2024-04-15 00:14:36,796 DEBUG overseer-0-5584f6c4db-bcn5q
2024-04-15 00:14:36,796 DEBUG --- stderr ---
2024-04-15 00:14:36,796 DEBUG
2024-04-15 00:14:36,797 INFO
2024-04-15 00:14:36,797 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/Reporting-authn-users-5000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/08_Verification/12_Reporting-authn-users-5000/stderr.txt
2024-04-15 00:14:36,797 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-04-15 00:14:37,569 INFO [loop_until]: OK (rc = 0)
2024-04-15 00:14:37,570 DEBUG --- stdout ---
2024-04-15 00:14:37,570 DEBUG tar: Removing leading `/' from member names
2024-04-15 00:14:37,570 DEBUG --- stderr ---
2024-04-15 00:14:37,570 DEBUG
2024-04-15 00:14:37,570 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/08_Verification/12_Reporting-authn-users-5000/stderr.txt is small (0 lines), printing all lines:
2024-04-15 00:14:37,570 DEBUG ----- output -----
2024-04-15 00:14:37,570 DEBUG
2024-04-15 00:14:37,570 INFO
________________________________________________________________________________
[2024-04-15 00:14:37] Reporting-authn-users-5000 step12 : Check stderr log
________________________________________________________________________________
PASS: no error found in stderr log
________________________________________________________________________________
[2024-04-15 00:14:37] Reporting-authn-users-5000 step13 : Analyze graphs for spikes
________________________________________________________________________________
Analyze Gatling graphs for spikes
checking parameters
open overall stats JSON file
checking simulation request userRestLogin
no spikes found
________________________________________________________________________________
[2024-04-15 00:14:37] Reporting-authn-users-5000 step14 : Collect Gatling simulation data
________________________________________________________________________________
collected stats :
{
"global": {
"name": "All Requests",
"numberOfRequests": {
"total": 18077268,
"ok": 18077264,
"ko": 4
},
"minResponseTime": {
"total": 4,
"ok": 8,
"ko": 4
},
"maxResponseTime": {
"total": 1054,
"ok": 1054,
"ko": 10
},
"meanResponseTime": {
"total": 19,
"ok": 19,
"ko": 6
},
"standardDeviation": {
"total": 15,
"ok": 15,
"ko": 2
},
"percentiles1": {
"total": 15,
"ok": 15,
"ko": 6
},
"percentiles2": {
"total": 20,
"ok": 20,
"ko": 7
},
"percentiles3": {
"total": 39,
"ok": 39,
"ko": 9
},
"percentiles4": {
"total": 74,
"ok": 74,
"ko": 10
},
"group1": {
"name": "t < 800 ms",
"htmlName": "t < 800 ms",
"count": 18077255,
"percentage": 100
},
"group2": {
"name": "800 ms <= t < 1200 ms",
"htmlName": "t >= 800 ms
t < 1200 ms",
"count": 9,
"percentage": 0
},
"group3": {
"name": "t >= 1200 ms",
"htmlName": "t >= 1200 ms",
"count": 0,
"percentage": 0
},
"group4": {
"name": "failed",
"htmlName": "failed",
"count": 4,
"percentage": 0
},
"meanNumberOfRequestsPerSecond": {
"total": 4978.592123381988,
"ok": 4978.591021757092,
"ko": 0.001101624896722666
}
},
"details": [
{
"name": "userRestLogin",
"numberOfRequests": {
"total": 18077268,
"ok": 18077264,
"ko": 4
},
"minResponseTime": {
"total": 4,
"ok": 8,
"ko": 4
},
"maxResponseTime": {
"total": 1054,
"ok": 1054,
"ko": 10
},
"meanResponseTime": {
"total": 19,
"ok": 19,
"ko": 6
},
"standardDeviation": {
"total": 15,
"ok": 15,
"ko": 2
},
"percentiles1": {
"total": 15,
"ok": 15,
"ko": 6
},
"percentiles2": {
"total": 20,
"ok": 20,
"ko": 7
},
"percentiles3": {
"total": 39,
"ok": 39,
"ko": 9
},
"percentiles4": {
"total": 74,
"ok": 74,
"ko": 10
},
"group1": {
"name": "t < 800 ms",
"htmlName": "t < 800 ms",
"count": 18077255,
"percentage": 100
},
"group2": {
"name": "800 ms <= t < 1200 ms",
"htmlName": "t >= 800 ms
t < 1200 ms",
"count": 9,
"percentage": 0
},
"group3": {
"name": "t >= 1200 ms",
"htmlName": "t >= 1200 ms",
"count": 0,
"percentage": 0
},
"group4": {
"name": "failed",
"htmlName": "failed",
"count": 4,
"percentage": 0
},
"meanNumberOfRequestsPerSecond": {
"total": 4978.592123381988,
"ok": 4978.591021757092,
"ko": 0.001101624896722666
},
"spikes": []
}
]
}
{
"concurrency": null,
"generic_stats": {
"avg_num_of_requests_per_second": 4979,
"avg_response_time": 19,
"max_response_time": 1054,
"min_response_time": 8,
"num_requests": 18077268,
"num_requests_pass": 18077264,
"num_requests_percent_pass": 100.0
},
"options": "; testMode:open; testCase:am.AMAuthN; throughput:5000; duration:3600",
"stats": {
"details": [
{
"group1": {
"count": 18077255,
"htmlName": "t < 800 ms",
"name": "t < 800 ms",
"percentage": 100
},
"group2": {
"count": 9,
"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": 4,
"htmlName": "failed",
"name": "failed",
"percentage": 0
},
"maxResponseTime": {
"ko": 10,
"ok": 1054,
"total": 1054
},
"meanNumberOfRequestsPerSecond": {
"ko": 0.001101624896722666,
"ok": 4978.591021757092,
"total": 4978.592123381988
},
"meanResponseTime": {
"ko": 6,
"ok": 19,
"total": 19
},
"minResponseTime": {
"ko": 4,
"ok": 8,
"total": 4
},
"name": "userRestLogin",
"numberOfRequests": {
"ko": 4,
"ok": 18077264,
"total": 18077268
},
"percentiles1": {
"ko": 6,
"ok": 15,
"total": 15
},
"percentiles2": {
"ko": 7,
"ok": 20,
"total": 20
},
"percentiles3": {
"ko": 9,
"ok": 39,
"total": 39
},
"percentiles4": {
"ko": 10,
"ok": 74,
"total": 74
},
"spikes": [],
"standardDeviation": {
"ko": 2,
"ok": 15,
"total": 15
}
}
],
"global": {
"group1": {
"count": 18077255,
"htmlName": "t < 800 ms",
"name": "t < 800 ms",
"percentage": 100
},
"group2": {
"count": 9,
"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": 4,
"htmlName": "failed",
"name": "failed",
"percentage": 0
},
"maxResponseTime": {
"ko": 10,
"ok": 1054,
"total": 1054
},
"meanNumberOfRequestsPerSecond": {
"ko": 0.001101624896722666,
"ok": 4978.591021757092,
"total": 4978.592123381988
},
"meanResponseTime": {
"ko": 6,
"ok": 19,
"total": 19
},
"minResponseTime": {
"ko": 4,
"ok": 8,
"total": 4
},
"name": "All Requests",
"numberOfRequests": {
"ko": 4,
"ok": 18077264,
"total": 18077268
},
"percentiles1": {
"ko": 6,
"ok": 15,
"total": 15
},
"percentiles2": {
"ko": 7,
"ok": 20,
"total": 20
},
"percentiles3": {
"ko": 9,
"ok": 39,
"total": 39
},
"percentiles4": {
"ko": 10,
"ok": 74,
"total": 74
},
"standardDeviation": {
"ko": 2,
"ok": 15,
"total": 15
}
}
},
"target_hostname": "xlou.iam.xlou-cdm.engineeringpit.com",
"tool_name": "gatling"
}
________________________________________________________________________________
[2024-04-15 00:14:37] Reporting-authn-users-5000 step15 : Update run description in the Gatling HTML report
________________________________________________________________________________
________________________________________________________________________________
[2024-04-15 00:14:37] Reporting-authn-users-5000 step16 : Remove gatling task's stdout file
________________________________________________________________________________
Cleanup of Gatling stdout file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/stdout.txt
Gatling stdout.txt found, but is small - leave it intact.
________________________________________________________________________________
[2024-04-15 00:14:37] Reporting-authn-users-5000 post : Check output
________________________________________________________________________________
Setting result to PASS
Task has been successfully stopped