--Task-- name: Reporting_revoke-access-token-1000 enabled: True class_name: GatlingResultTask source_name: overseer-0 source_namespace: >default< target_name: overseer-0 target_namespace: >default< start: 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_access-token-50', 'Reporting_revoke-access-token-50', 'Reporting_access-token-200', 'Reporting_revoke-access-token-200', 'Reporting_access-token-400', 'Reporting_revoke-access-token-400', 'Reporting_access-token-600', 'Reporting_revoke-access-token-600', 'Reporting_access-token-800', 'Reporting_revoke-access-token-800', 'Reporting_access-token-900', 'Reporting_revoke-access-token-900', 'Reporting_access-token-1000'] options: {'task_name': 'revoke-access-token-1000'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [04/Feb/2023 19:24:10] Reporting_revoke-access-token-1000 pre : N/A ________________________________________________________________________________ source (overseer-0) is a component [04/Feb/2023 19:24:10] - INFO: Timeout for this Task has changed to 1h (3600 seconds) [04/Feb/2023 19:24:10] - INFO: timeout was set to GatlingResultTask default, because it was unset Pod stdout file : /results/orders/Reporting_revoke-access-token-1000/stdout.txt Pod stderr file : /results/orders/Reporting_revoke-access-token-1000/stderr.txt Pod log : /results/orders/Reporting_revoke-access-token-1000 ________________________________________________________________________________ [04/Feb/2023 19:24:10] Reporting_revoke-access-token-1000 step1 : Write order for overseer to generate the report ________________________________________________________________________________ Order file : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/22_Reporting_revoke-access-token-1000/order.json { "name": "Reporting_revoke-access-token-1000", "command": "/lodestar/clients/gatling-report-only-for-pyrock.sh revoke-access-token-1000", "out-dir": "/results/orders/Reporting_revoke-access-token-1000" } ________________________________________________________________________________ [04/Feb/2023 19:24:10] Reporting_revoke-access-token-1000 step2 : Make sure overseer is ready to accept order (not already busy) ________________________________________________________________________________ [loop_until]: curl -k https://overseer-0.xlou.iam.xlou-bsln.engineeringpit.com/orders/status.txt | grep "WAITING" [loop_until]: (max_time=120, interval=5, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found [loop_until]: OK (rc = 0) --- stdout --- WAITING --- stderr --- % 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 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [04/Feb/2023 19:24:10] Reporting_revoke-access-token-1000 step3 : Send order to overseer ________________________________________________________________________________ [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [loop_until]: kubectl --namespace=xlou cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/22_Reporting_revoke-access-token-1000/order.json overseer-0-56bb5996b-nq898:/results/orders/order.json [loop_until]: (max_time=180, interval=5, expected_rc=[0] [loop_until]: OK (rc = 0) --- stdout --- --- stderr --- Order has been successfully forwarded to overseer ________________________________________________________________________________ [04/Feb/2023 19:24:12] Reporting_revoke-access-token-1000 step4 : Wait order is done ________________________________________________________________________________ Workaround for PERF-1966. Sleeping for 5 seconds. [loop_until]: curl -k https://overseer-0.xlou.iam.xlou-bsln.engineeringpit.com/orders/status.txt | grep "WAITING" [loop_until]: (max_time=3600, interval=5, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 5s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 10s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 15s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 21s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 26s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 31s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 36s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 42s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 47s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 52s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 57s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 1m 03s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 1m 08s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 1m 13s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 1m 18s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 1m 24s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 1m 29s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 1m 34s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 1m 39s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 1m 44s (rc=0) - expected pattern found [loop_until]: OK (rc = 0) --- stdout --- WAITING --- stderr --- % 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 34 0 --:--:-- --:--:-- --:--:-- 34 ________________________________________________________________________________ [04/Feb/2023 19:26:02] Reporting_revoke-access-token-1000 step5 : Wait until gatling report is generated on overseer ________________________________________________________________________________ [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- Checking pod: overseer-0-56bb5996b-nq898 for file exists, if not wait. [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [loop_until]: kubectl --namespace=xlou exec overseer-0-56bb5996b-nq898 -i -- bash -c 'if [ -f "/results/gatling/revoke-access-token-1000.tar.gz" ]; then echo "/results/gatling/revoke-access-token-1000.tar.gz file found"; exit 0; else echo "/results/gatling/revoke-access-token-1000.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/revoke-access-token-1000.tar.gz file found" [loop_until]: (max_time=180, interval=5, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found [loop_until]: OK (rc = 0) --- stdout --- /results/gatling/revoke-access-token-1000.tar.gz file found --- stderr --- [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [run_command]: kubectl --namespace=xlou exec overseer-0-56bb5996b-nq898 -i -- du -k /results/gatling/revoke-access-token-1000.tar.gz [run_command]: OK (rc = 0 - expected to be in [0]) --- stdout --- 420 /results/gatling/revoke-access-token-1000.tar.gz --- stderr --- - Download file (size 420k) [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- source : /results/gatling/revoke-access-token-1000.tar.gz @ overseer-0-56bb5996b-nq898 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/shared/revoke-access-token-1000.tar.gz [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [loop_until]: kubectl --namespace=xlou cp overseer-0-56bb5996b-nq898:/results/gatling/revoke-access-token-1000.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/shared/revoke-access-token-1000.tar.gz [loop_until]: (max_time=-1, interval=5, expected_rc=[0] [loop_until]: OK (rc = 0) --- stdout --- tar: Removing leading `/' from member names --- stderr --- ________________________________________________________________________________ [04/Feb/2023 19:26:06] Reporting_revoke-access-token-1000 step6 : Unzip the result ________________________________________________________________________________ [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/shared/revoke-access-token-1000.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/06_AccessToken/14_revoke-access-token-1000/gatling [run_command]: OK (rc = 0 - expected to be in [0]) --- stdout --- --- stderr --- ________________________________________________________________________________ [04/Feb/2023 19:26:06] Reporting_revoke-access-token-1000 step7 : Print rc order ________________________________________________________________________________ [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- Checking pod: overseer-0-56bb5996b-nq898 for file exists, if not wait. [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [loop_until]: kubectl --namespace=xlou exec overseer-0-56bb5996b-nq898 -i -- bash -c 'if [ -f "/results/orders/Reporting_revoke-access-token-1000/rc.txt" ]; then echo "/results/orders/Reporting_revoke-access-token-1000/rc.txt file found"; exit 0; else echo "/results/orders/Reporting_revoke-access-token-1000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting_revoke-access-token-1000/rc.txt file found" [loop_until]: (max_time=180, interval=5, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found [loop_until]: OK (rc = 0) --- stdout --- /results/orders/Reporting_revoke-access-token-1000/rc.txt file found --- stderr --- [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [run_command]: kubectl --namespace=xlou exec overseer-0-56bb5996b-nq898 -i -- du -k /results/orders/Reporting_revoke-access-token-1000/rc.txt [run_command]: OK (rc = 0 - expected to be in [0]) --- stdout --- 4 /results/orders/Reporting_revoke-access-token-1000/rc.txt --- stderr --- - Download file (size 4k) [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- source : /results/orders/Reporting_revoke-access-token-1000/rc.txt @ overseer-0-56bb5996b-nq898 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/22_Reporting_revoke-access-token-1000/rc.txt [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [loop_until]: kubectl --namespace=xlou cp overseer-0-56bb5996b-nq898:/results/orders/Reporting_revoke-access-token-1000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/22_Reporting_revoke-access-token-1000/rc.txt [loop_until]: (max_time=-1, interval=5, expected_rc=[0] [loop_until]: OK (rc = 0) --- stdout --- tar: Removing leading `/' from member names --- stderr --- [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/22_Reporting_revoke-access-token-1000/rc.txt is small (1 lines), printing all lines: ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [04/Feb/2023 19:26:10] Reporting_revoke-access-token-1000 step8 : Check rc log ________________________________________________________________________________ PASS: no error found in rc log ________________________________________________________________________________ [04/Feb/2023 19:26:10] Reporting_revoke-access-token-1000 step9 : Print stdout order ________________________________________________________________________________ [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- Checking pod: overseer-0-56bb5996b-nq898 for file exists, if not wait. [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [loop_until]: kubectl --namespace=xlou exec overseer-0-56bb5996b-nq898 -i -- bash -c 'if [ -f "/results/orders/Reporting_revoke-access-token-1000/stdout.txt" ]; then echo "/results/orders/Reporting_revoke-access-token-1000/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting_revoke-access-token-1000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting_revoke-access-token-1000/stdout.txt file found" [loop_until]: (max_time=180, interval=5, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found [loop_until]: OK (rc = 0) --- stdout --- /results/orders/Reporting_revoke-access-token-1000/stdout.txt file found --- stderr --- [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [run_command]: kubectl --namespace=xlou exec overseer-0-56bb5996b-nq898 -i -- du -k /results/orders/Reporting_revoke-access-token-1000/stdout.txt [run_command]: OK (rc = 0 - expected to be in [0]) --- stdout --- 8 /results/orders/Reporting_revoke-access-token-1000/stdout.txt --- stderr --- - Download file (size 8k) [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- source : /results/orders/Reporting_revoke-access-token-1000/stdout.txt @ overseer-0-56bb5996b-nq898 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/22_Reporting_revoke-access-token-1000/stdout.txt [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [loop_until]: kubectl --namespace=xlou cp overseer-0-56bb5996b-nq898:/results/orders/Reporting_revoke-access-token-1000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/22_Reporting_revoke-access-token-1000/stdout.txt [loop_until]: (max_time=-1, interval=5, expected_rc=[0] [loop_until]: OK (rc = 0) --- stdout --- tar: Removing leading `/' from member names --- stderr --- [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/22_Reporting_revoke-access-token-1000/stdout.txt is small (98 lines), printing all lines: ----- output ----- [cmd] /lodestar/clients/gatling/bin/gatling.sh -ro /results/gatling/revoke-access-token-1000/loadtest-20230204174741288 GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Parsing log file(s)... Parsing log file(s) done Generating reports... ================================================================================ ---- Global Information -------------------------------------------------------- > request count 13486393 (OK=13157464 KO=328929) > min response time 3 (OK=3 KO=5 ) > max response time 60656 (OK=60327 KO=60656 ) > mean response time 3728 (OK=3666 KO=6200 ) > std deviation 4540 (OK=4071 KO=13256 ) > response time 50th percentile 936 (OK=1009 KO=47 ) > response time 75th percentile 7379 (OK=7377 KO=14993 ) > response time 95th percentile 9587 (OK=9390 KO=29693 ) > response time 99th percentile 15565 (OK=13279 KO=60005 ) > mean requests/sec 3690.857 (OK=3600.839 KO=90.019) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 6332028 ( 47%) > 800 ms < t < 1200 ms 426079 ( 3%) > t > 1200 ms 6399357 ( 47%) > failed 328929 ( 2%) ---- Errors -------------------------------------------------------------------- > RevokeToken: Failed to build request: No attribute named 'refr 325963 (25.12%) esh_token' is defined > getAccessToken: Failed to build request: No attribute named 'a 322939 (24.89%) uthcode' is defined > userAuthorize: Failed to build request: No attribute named 'to 319841 (24.65%) kenId' is defined > i.n.c.AbstractChannel$AnnotatedSocketException: Cannot assign 242132 (18.66%) requested address: xlou.iam.xlou-bsln.engineeringpit.com/34.14... > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 51275 ( 3.95%) ), but actually found 504 > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 15405 ( 1.19%) ), but actually found 502 > i.g.h.c.i.RequestTimeoutException: Request timeout to xlou.iam 12573 ( 0.97%) .xlou-bsln.engineeringpit.com/34.148.47.242:443 after 60000 ms > headerRegex((Location,(?<=code=)(.+?)(?=&))).find.exists, foun 2579 ( 0.20%) d nothing > jsonPath($.access_token).find.exists preparation crashed: Jack 2508 ( 0.19%) son failed to parse into a valid AST: c.f.j.c.JsonParseExcepti... > status.find.is(200), but actually found 504 1789 ( 0.14%) > status.find.is(200), but actually found 502 668 ( 0.05%) ================================================================================ Reports generated in 0s. Please open the following file: /results/gatling/revoke-access-token-1000/loadtest-20230204174741288/index.html ---------------------- [04/Feb/2023 19:25:58] Create tar file /results/gatling/revoke-access-token-1000.tar.gz ---------------------- SIMULATION_PATH=/results/gatling/revoke-access-token-1000/loadtest-20230204174741288 RESULT_FOLDER=revoke-access-token-1000 RESULT_PATH=/results/gatling/revoke-access-token-1000 PWD=/results/gatling/revoke-access-token-1000/loadtest-20230204174741288 [cmd] tar -czvf /results/gatling/revoke-access-token-1000.tar.gz --exclude=simulation.log * index.html js/ js/stats.js js/bootstrap.min.js js/theme.js js/assertions.xml js/menu.js js/gatling.js js/highcharts-more.js js/all_sessions.js js/jquery.min.js js/stats.json js/moment.min.js js/global_stats.json js/assertions.json js/highstock.js js/unpack.js req_getaccesstoken-362c1.html req_revoketoken-0966a.html req_userauthorize-f29d0.html req_userrestlogin-03b82.html style/ style/sorted-down.png style/favicon.ico style/sorted-up.png style/logo.png style/bootstrap.min.css style/style.css style/arrow_down.png style/sortable.png style/little_arrow_right.png style/stat-l-temps.png style/stat-l-roue.png style/logo.svg style/cible.png style/arrow_right_black.png style/arrow_down_black.png style/logo-gatling.jpg style/stat-fleche-bas.png style/arrow_right.png style/stat-fond.png ________________________________________________________________________________ [04/Feb/2023 19:26:13] Reporting_revoke-access-token-1000 step10 : Check stdout log ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [04/Feb/2023 19:26:13] Reporting_revoke-access-token-1000 step11 : Print stderr order ________________________________________________________________________________ [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- Checking pod: overseer-0-56bb5996b-nq898 for file exists, if not wait. [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [loop_until]: kubectl --namespace=xlou exec overseer-0-56bb5996b-nq898 -i -- bash -c 'if [ -f "/results/orders/Reporting_revoke-access-token-1000/stderr.txt" ]; then echo "/results/orders/Reporting_revoke-access-token-1000/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting_revoke-access-token-1000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting_revoke-access-token-1000/stderr.txt file found" [loop_until]: (max_time=180, interval=5, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found [loop_until]: OK (rc = 0) --- stdout --- /results/orders/Reporting_revoke-access-token-1000/stderr.txt file found --- stderr --- [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [run_command]: kubectl --namespace=xlou exec overseer-0-56bb5996b-nq898 -i -- du -k /results/orders/Reporting_revoke-access-token-1000/stderr.txt [run_command]: OK (rc = 0 - expected to be in [0]) --- stdout --- 0 /results/orders/Reporting_revoke-access-token-1000/stderr.txt --- stderr --- - Download file (size 0k) [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- source : /results/orders/Reporting_revoke-access-token-1000/stderr.txt @ overseer-0-56bb5996b-nq898 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/22_Reporting_revoke-access-token-1000/stderr.txt [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou get pods -l app=overseer-0 -o jsonpath={.items[*].metadata.name}` | grep 1 [loop_until]: (max_time=180, interval=10, expected_rc=[0] [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found [loop_until]: OK (rc = 0) --- stdout --- overseer-0-56bb5996b-nq898 --- stderr --- [loop_until]: kubectl --namespace=xlou cp overseer-0-56bb5996b-nq898:/results/orders/Reporting_revoke-access-token-1000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/22_Reporting_revoke-access-token-1000/stderr.txt [loop_until]: (max_time=-1, interval=5, expected_rc=[0] [loop_until]: OK (rc = 0) --- stdout --- tar: Removing leading `/' from member names --- stderr --- [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/22_Reporting_revoke-access-token-1000/stderr.txt is small (0 lines), printing all lines: ----- output ----- ________________________________________________________________________________ [04/Feb/2023 19:26:17] Reporting_revoke-access-token-1000 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [04/Feb/2023 19:26:17] Reporting_revoke-access-token-1000 step13 : Analyze graphs for spikes ________________________________________________________________________________ Analyze Gatling graphs for spikes checking parameters open overall stats JSON file checking simulation request userRestLogin checking simulation request userAuthorize checking simulation request getAccessToken checking simulation request RevokeToken 5 spikes found - see "spikes" sections in js/stats.json ________________________________________________________________________________ [04/Feb/2023 19:26:17] Reporting_revoke-access-token-1000 step14 : Collect gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "Global Information", "numberOfRequests": { "total": 13486393, "ok": 13157464, "ko": 328929 }, "minResponseTime": { "total": 3, "ok": 3, "ko": 5 }, "maxResponseTime": { "total": 60656, "ok": 60327, "ko": 60656 }, "meanResponseTime": { "total": 3728, "ok": 3666, "ko": 6200 }, "standardDeviation": { "total": 4540, "ok": 4071, "ko": 13256 }, "percentiles1": { "total": 936, "ok": 1009, "ko": 47 }, "percentiles2": { "total": 7379, "ok": 7377, "ko": 14993 }, "percentiles3": { "total": 9587, "ok": 9390, "ko": 29693 }, "percentiles4": { "total": 15565, "ok": 13279, "ko": 60005 }, "group1": { "name": "t < 800 ms", "count": 6332028, "percentage": 47 }, "group2": { "name": "800 ms < t < 1200 ms", "count": 426079, "percentage": 3 }, "group3": { "name": "t > 1200 ms", "count": 6399357, "percentage": 47 }, "group4": { "name": "failed", "count": 328929, "percentage": 2 }, "meanNumberOfRequestsPerSecond": { "total": 3690.8574165298305, "ok": 3600.838533114395, "ko": 90.01888341543514 } }, "details": [ { "name": "userRestLogin", "numberOfRequests": { "total": 3613784, "ok": 3293943, "ko": 319841 }, "minResponseTime": { "total": 5, "ok": 10, "ko": 5 }, "maxResponseTime": { "total": 60656, "ok": 60327, "ko": 60656 }, "meanResponseTime": { "total": 4018, "ok": 3856, "ko": 5680 }, "standardDeviation": { "total": 5967, "ok": 4795, "ko": 12749 }, "percentiles1": { "total": 405, "ok": 554, "ko": 46 }, "percentiles2": { "total": 7052, "ok": 7056, "ko": 197 }, "percentiles3": { "total": 13552, "ok": 12396, "ko": 27738 }, "percentiles4": { "total": 17753, "ok": 17285, "ko": 60005 }, "group1": { "name": "t < 800 ms", "count": 1732669, "percentage": 48 }, "group2": { "name": "800 ms < t < 1200 ms", "count": 77488, "percentage": 2 }, "group3": { "name": "t > 1200 ms", "count": 1483786, "percentage": 41 }, "group4": { "name": "failed", "count": 319841, "percentage": 9 }, "meanNumberOfRequestsPerSecond": { "total": 988.9939792008757, "ok": 901.4622331691297, "ko": 87.53174603174604 }, "spikes": [ { "datetime": "2023-02-04T18:19:45", "maxResponseTime": 57090 } ] }, { "name": "userAuthorize", "numberOfRequests": { "total": 3293943, "ok": 3290845, "ko": 3098 }, "minResponseTime": { "total": 3, "ok": 3, "ko": 13022 }, "maxResponseTime": { "total": 60558, "ok": 60006, "ko": 60558 }, "meanResponseTime": { "total": 3238, "ok": 3218, "ko": 24489 }, "standardDeviation": { "total": 3645, "ok": 3550, "ko": 17078 }, "percentiles1": { "total": 538, "ok": 535, "ko": 15004 }, "percentiles2": { "total": 7015, "ok": 7012, "ko": 27508 }, "percentiles3": { "total": 7968, "ok": 7960, "ko": 60001 }, "percentiles4": { "total": 8790, "ok": 8722, "ko": 60183 }, "group1": { "name": "t < 800 ms", "count": 1738245, "percentage": 53 }, "group2": { "name": "800 ms < t < 1200 ms", "count": 73291, "percentage": 2 }, "group3": { "name": "t > 1200 ms", "count": 1479309, "percentage": 45 }, "group4": { "name": "failed", "count": 3098, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 901.4622331691297, "ok": 900.6143951833607, "ko": 0.8478379857690203 }, "spikes": [] }, { "name": "getAccessToken", "numberOfRequests": { "total": 3290845, "ok": 3287821, "ko": 3024 }, "minResponseTime": { "total": 6, "ok": 6, "ko": 13130 }, "maxResponseTime": { "total": 60537, "ok": 59394, "ko": 60537 }, "meanResponseTime": { "total": 3909, "ok": 3890, "ko": 24522 }, "standardDeviation": { "total": 3987, "ok": 3905, "ko": 17219 }, "percentiles1": { "total": 1667, "ok": 1662, "ko": 15005 }, "percentiles2": { "total": 7918, "ok": 7914, "ko": 26806 }, "percentiles3": { "total": 9428, "ok": 9415, "ko": 60002 }, "percentiles4": { "total": 10663, "ok": 10565, "ko": 60201 }, "group1": { "name": "t < 800 ms", "count": 1353722, "percentage": 41 }, "group2": { "name": "800 ms < t < 1200 ms", "count": 135983, "percentage": 4 }, "group3": { "name": "t > 1200 ms", "count": 1798116, "percentage": 55 }, "group4": { "name": "failed", "count": 3024, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 900.6143951833607, "ok": 899.7868089764642, "ko": 0.8275862068965517 }, "spikes": [ { "datetime": "2023-02-04T18:07:13", "maxResponseTime": 2057 }, { "datetime": "2023-02-04T18:07:16", "maxResponseTime": 3604 } ] }, { "name": "RevokeToken", "numberOfRequests": { "total": 3287821, "ok": 3284855, "ko": 2966 }, "minResponseTime": { "total": 3, "ok": 3, "ko": 13111 }, "maxResponseTime": { "total": 60570, "ok": 59927, "ko": 60570 }, "meanResponseTime": { "total": 3720, "ok": 3701, "ko": 24539 }, "standardDeviation": { "total": 3976, "ok": 3894, "ko": 17198 }, "percentiles1": { "total": 1191, "ok": 1187, "ko": 15004 }, "percentiles2": { "total": 7821, "ok": 7817, "ko": 27413 }, "percentiles3": { "total": 9061, "ok": 9051, "ko": 60001 }, "percentiles4": { "total": 10137, "ok": 10047, "ko": 60215 }, "group1": { "name": "t < 800 ms", "count": 1507392, "percentage": 46 }, "group2": { "name": "800 ms < t < 1200 ms", "count": 139317, "percentage": 4 }, "group3": { "name": "t > 1200 ms", "count": 1638146, "percentage": 50 }, "group4": { "name": "failed", "count": 2966, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 899.7868089764642, "ok": 898.9750957854407, "ko": 0.8117131910235359 }, "spikes": [ { "datetime": "2023-02-04T18:07:16", "maxResponseTime": 2588 }, { "datetime": "2023-02-04T18:18:14", "maxResponseTime": 2457 } ] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 3691, "avg_response_time": 3728, "max_response_time": 60327, "min_response_time": 3, "num_requests": 13486393, "num_requests_pass": 13157464, "num_requests_percent_pass": 97.561 }, "options": "; rampup-duration:30; simulation:com.forgerock.gatling.Loadtest; testCase:am.AMAccessToken; testMode:open; throughput:1000", "stats": { "details": [ { "group1": { "count": 1732669, "name": "t < 800 ms", "percentage": 48 }, "group2": { "count": 77488, "name": "800 ms < t < 1200 ms", "percentage": 2 }, "group3": { "count": 1483786, "name": "t > 1200 ms", "percentage": 41 }, "group4": { "count": 319841, "name": "failed", "percentage": 9 }, "maxResponseTime": { "ko": 60656, "ok": 60327, "total": 60656 }, "meanNumberOfRequestsPerSecond": { "ko": 87.53174603174604, "ok": 901.4622331691297, "total": 988.9939792008757 }, "meanResponseTime": { "ko": 5680, "ok": 3856, "total": 4018 }, "minResponseTime": { "ko": 5, "ok": 10, "total": 5 }, "name": "userRestLogin", "numberOfRequests": { "ko": 319841, "ok": 3293943, "total": 3613784 }, "percentiles1": { "ko": 46, "ok": 554, "total": 405 }, "percentiles2": { "ko": 197, "ok": 7056, "total": 7052 }, "percentiles3": { "ko": 27738, "ok": 12396, "total": 13552 }, "percentiles4": { "ko": 60005, "ok": 17285, "total": 17753 }, "spikes": [ { "datetime": "2023-02-04T18:19:45", "maxResponseTime": 57090 } ], "standardDeviation": { "ko": 12749, "ok": 4795, "total": 5967 } }, { "group1": { "count": 1738245, "name": "t < 800 ms", "percentage": 53 }, "group2": { "count": 73291, "name": "800 ms < t < 1200 ms", "percentage": 2 }, "group3": { "count": 1479309, "name": "t > 1200 ms", "percentage": 45 }, "group4": { "count": 3098, "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 60558, "ok": 60006, "total": 60558 }, "meanNumberOfRequestsPerSecond": { "ko": 0.8478379857690203, "ok": 900.6143951833607, "total": 901.4622331691297 }, "meanResponseTime": { "ko": 24489, "ok": 3218, "total": 3238 }, "minResponseTime": { "ko": 13022, "ok": 3, "total": 3 }, "name": "userAuthorize", "numberOfRequests": { "ko": 3098, "ok": 3290845, "total": 3293943 }, "percentiles1": { "ko": 15004, "ok": 535, "total": 538 }, "percentiles2": { "ko": 27508, "ok": 7012, "total": 7015 }, "percentiles3": { "ko": 60001, "ok": 7960, "total": 7968 }, "percentiles4": { "ko": 60183, "ok": 8722, "total": 8790 }, "spikes": [], "standardDeviation": { "ko": 17078, "ok": 3550, "total": 3645 } }, { "group1": { "count": 1353722, "name": "t < 800 ms", "percentage": 41 }, "group2": { "count": 135983, "name": "800 ms < t < 1200 ms", "percentage": 4 }, "group3": { "count": 1798116, "name": "t > 1200 ms", "percentage": 55 }, "group4": { "count": 3024, "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 60537, "ok": 59394, "total": 60537 }, "meanNumberOfRequestsPerSecond": { "ko": 0.8275862068965517, "ok": 899.7868089764642, "total": 900.6143951833607 }, "meanResponseTime": { "ko": 24522, "ok": 3890, "total": 3909 }, "minResponseTime": { "ko": 13130, "ok": 6, "total": 6 }, "name": "getAccessToken", "numberOfRequests": { "ko": 3024, "ok": 3287821, "total": 3290845 }, "percentiles1": { "ko": 15005, "ok": 1662, "total": 1667 }, "percentiles2": { "ko": 26806, "ok": 7914, "total": 7918 }, "percentiles3": { "ko": 60002, "ok": 9415, "total": 9428 }, "percentiles4": { "ko": 60201, "ok": 10565, "total": 10663 }, "spikes": [ { "datetime": "2023-02-04T18:07:13", "maxResponseTime": 2057 }, { "datetime": "2023-02-04T18:07:16", "maxResponseTime": 3604 } ], "standardDeviation": { "ko": 17219, "ok": 3905, "total": 3987 } }, { "group1": { "count": 1507392, "name": "t < 800 ms", "percentage": 46 }, "group2": { "count": 139317, "name": "800 ms < t < 1200 ms", "percentage": 4 }, "group3": { "count": 1638146, "name": "t > 1200 ms", "percentage": 50 }, "group4": { "count": 2966, "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 60570, "ok": 59927, "total": 60570 }, "meanNumberOfRequestsPerSecond": { "ko": 0.8117131910235359, "ok": 898.9750957854407, "total": 899.7868089764642 }, "meanResponseTime": { "ko": 24539, "ok": 3701, "total": 3720 }, "minResponseTime": { "ko": 13111, "ok": 3, "total": 3 }, "name": "RevokeToken", "numberOfRequests": { "ko": 2966, "ok": 3284855, "total": 3287821 }, "percentiles1": { "ko": 15004, "ok": 1187, "total": 1191 }, "percentiles2": { "ko": 27413, "ok": 7817, "total": 7821 }, "percentiles3": { "ko": 60001, "ok": 9051, "total": 9061 }, "percentiles4": { "ko": 60215, "ok": 10047, "total": 10137 }, "spikes": [ { "datetime": "2023-02-04T18:07:16", "maxResponseTime": 2588 }, { "datetime": "2023-02-04T18:18:14", "maxResponseTime": 2457 } ], "standardDeviation": { "ko": 17198, "ok": 3894, "total": 3976 } } ], "global": { "group1": { "count": 6332028, "name": "t < 800 ms", "percentage": 47 }, "group2": { "count": 426079, "name": "800 ms < t < 1200 ms", "percentage": 3 }, "group3": { "count": 6399357, "name": "t > 1200 ms", "percentage": 47 }, "group4": { "count": 328929, "name": "failed", "percentage": 2 }, "maxResponseTime": { "ko": 60656, "ok": 60327, "total": 60656 }, "meanNumberOfRequestsPerSecond": { "ko": 90.01888341543514, "ok": 3600.838533114395, "total": 3690.8574165298305 }, "meanResponseTime": { "ko": 6200, "ok": 3666, "total": 3728 }, "minResponseTime": { "ko": 5, "ok": 3, "total": 3 }, "name": "Global Information", "numberOfRequests": { "ko": 328929, "ok": 13157464, "total": 13486393 }, "percentiles1": { "ko": 47, "ok": 1009, "total": 936 }, "percentiles2": { "ko": 14993, "ok": 7377, "total": 7379 }, "percentiles3": { "ko": 29693, "ok": 9390, "total": 9587 }, "percentiles4": { "ko": 60005, "ok": 13279, "total": 15565 }, "standardDeviation": { "ko": 13256, "ok": 4071, "total": 4540 } } }, "target_hostname": "xlou.iam.xlou-bsln.engineeringpit.com", "tool_name": "gatling" } ________________________________________________________________________________ [04/Feb/2023 19:26:17] Reporting_revoke-access-token-1000 step15 : Remove gatling task's stdout file ________________________________________________________________________________ Deleting gatling stdout file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/06_AccessToken/14_revoke-access-token-1000/stdout.txt Gatling stdout.txt has been deleted ________________________________________________________________________________ [04/Feb/2023 19:26:17] Reporting_revoke-access-token-1000 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped