--Task-- name: Reporting_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'] options: {'task_name': 'access-token-1000'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [04/Feb/2023 19:20:40] Reporting_access-token-1000 pre : N/A ________________________________________________________________________________ source (overseer-0) is a component [04/Feb/2023 19:20:40] - INFO: Timeout for this Task has changed to 1h (3600 seconds) [04/Feb/2023 19:20:40] - INFO: timeout was set to GatlingResultTask default, because it was unset Pod stdout file : /results/orders/Reporting_access-token-1000/stdout.txt Pod stderr file : /results/orders/Reporting_access-token-1000/stderr.txt Pod log : /results/orders/Reporting_access-token-1000 ________________________________________________________________________________ [04/Feb/2023 19:20:40] Reporting_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/21_Reporting_access-token-1000/order.json { "name": "Reporting_access-token-1000", "command": "/lodestar/clients/gatling-report-only-for-pyrock.sh access-token-1000", "out-dir": "/results/orders/Reporting_access-token-1000" } ________________________________________________________________________________ [04/Feb/2023 19:20:40] Reporting_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 100 7 100 7 0 0 34 0 --:--:-- --:--:-- --:--:-- 34 ________________________________________________________________________________ [04/Feb/2023 19:20:40] Reporting_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/21_Reporting_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:20:42] Reporting_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) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 1m 50s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 1m 55s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m 05s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m 11s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m 16s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m 21s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m 26s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m 32s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m 37s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m 42s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m 47s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m 53s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 2m 58s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 3m 03s (rc=0) - failed to find expected output: WAITING - retry [loop_until]: Function succeeded after 3m 08s (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:23:55] Reporting_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/access-token-1000.tar.gz" ]; then echo "/results/gatling/access-token-1000.tar.gz file found"; exit 0; else echo "/results/gatling/access-token-1000.tar.gz file not found"; exit 1; fi' | grep "/results/gatling/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/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/access-token-1000.tar.gz [run_command]: OK (rc = 0 - expected to be in [0]) --- stdout --- 336 /results/gatling/access-token-1000.tar.gz --- stderr --- - Download file (size 336k) [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/access-token-1000.tar.gz @ overseer-0-56bb5996b-nq898 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/shared/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/access-token-1000.tar.gz /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/shared/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:23:59] Reporting_access-token-1000 step6 : Unzip the result ________________________________________________________________________________ [run_command]: tar zxf /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/shared/access-token-1000.tar.gz -C /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/06_AccessToken/13_access-token-1000/gatling [run_command]: OK (rc = 0 - expected to be in [0]) --- stdout --- --- stderr --- ________________________________________________________________________________ [04/Feb/2023 19:24:00] Reporting_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_access-token-1000/rc.txt" ]; then echo "/results/orders/Reporting_access-token-1000/rc.txt file found"; exit 0; else echo "/results/orders/Reporting_access-token-1000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting_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_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_access-token-1000/rc.txt [run_command]: OK (rc = 0 - expected to be in [0]) --- stdout --- 4 /results/orders/Reporting_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_access-token-1000/rc.txt @ overseer-0-56bb5996b-nq898 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/21_Reporting_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_access-token-1000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/21_Reporting_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/21_Reporting_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:24:03] Reporting_access-token-1000 step8 : Check rc log ________________________________________________________________________________ PASS: no error found in rc log ________________________________________________________________________________ [04/Feb/2023 19:24:03] Reporting_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_access-token-1000/stdout.txt" ]; then echo "/results/orders/Reporting_access-token-1000/stdout.txt file found"; exit 0; else echo "/results/orders/Reporting_access-token-1000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting_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_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_access-token-1000/stdout.txt [run_command]: OK (rc = 0 - expected to be in [0]) --- stdout --- 4 /results/orders/Reporting_access-token-1000/stdout.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_access-token-1000/stdout.txt @ overseer-0-56bb5996b-nq898 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/21_Reporting_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_access-token-1000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/21_Reporting_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/21_Reporting_access-token-1000/stdout.txt is small (77 lines), printing all lines: ----- output ----- [cmd] /lodestar/clients/gatling/bin/gatling.sh -ro /results/gatling/access-token-1000/loadtest-20230204164517714 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 14462404 (OK=14462404 KO=0 ) > min response time 1 (OK=1 KO=- ) > max response time 2283 (OK=2283 KO=- ) > mean response time 44 (OK=44 KO=- ) > std deviation 83 (OK=83 KO=- ) > response time 50th percentile 15 (OK=15 KO=- ) > response time 75th percentile 43 (OK=43 KO=- ) > response time 95th percentile 180 (OK=180 KO=- ) > response time 99th percentile 425 (OK=425 KO=- ) > mean requests/sec 3983.036 (OK=3983.036 KO=- ) ---- Response Time Distribution ------------------------------------------------ > t < 800 ms 14446249 (100%) > 800 ms < t < 1200 ms 14692 ( 0%) > t > 1200 ms 1463 ( 0%) > failed 0 ( 0%) ================================================================================ Reports generated in 0s. Please open the following file: /results/gatling/access-token-1000/loadtest-20230204164517714/index.html ---------------------- [04/Feb/2023 19:23:54] Create tar file /results/gatling/access-token-1000.tar.gz ---------------------- SIMULATION_PATH=/results/gatling/access-token-1000/loadtest-20230204164517714 RESULT_FOLDER=access-token-1000 RESULT_PATH=/results/gatling/access-token-1000 PWD=/results/gatling/access-token-1000/loadtest-20230204164517714 [cmd] tar -czvf /results/gatling/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_gettokeninfo-e3de3.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:24:07] Reporting_access-token-1000 step10 : Check stdout log ________________________________________________________________________________ PASS: no error found in stdout log ________________________________________________________________________________ [04/Feb/2023 19:24:07] Reporting_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_access-token-1000/stderr.txt" ]; then echo "/results/orders/Reporting_access-token-1000/stderr.txt file found"; exit 0; else echo "/results/orders/Reporting_access-token-1000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/Reporting_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_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_access-token-1000/stderr.txt [run_command]: OK (rc = 0 - expected to be in [0]) --- stdout --- 0 /results/orders/Reporting_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_access-token-1000/stderr.txt @ overseer-0-56bb5996b-nq898 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/21_Reporting_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_access-token-1000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/access_token/07_Verification/21_Reporting_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/21_Reporting_access-token-1000/stderr.txt is small (0 lines), printing all lines: ----- output ----- ________________________________________________________________________________ [04/Feb/2023 19:24:10] Reporting_access-token-1000 step12 : Check stderr log ________________________________________________________________________________ PASS: no error found in stderr log ________________________________________________________________________________ [04/Feb/2023 19:24:10] Reporting_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 getTokenInfo 2 spikes found - see "spikes" sections in js/stats.json ________________________________________________________________________________ [04/Feb/2023 19:24:10] Reporting_access-token-1000 step14 : Collect gatling simulation data ________________________________________________________________________________ collected stats : { "global": { "name": "Global Information", "numberOfRequests": { "total": 14462404, "ok": 14462404, "ko": 0 }, "minResponseTime": { "total": 1, "ok": 1, "ko": 0 }, "maxResponseTime": { "total": 2283, "ok": 2283, "ko": 0 }, "meanResponseTime": { "total": 44, "ok": 44, "ko": 0 }, "standardDeviation": { "total": 83, "ok": 83, "ko": 0 }, "percentiles1": { "total": 15, "ok": 15, "ko": 0 }, "percentiles2": { "total": 43, "ok": 43, "ko": 0 }, "percentiles3": { "total": 180, "ok": 180, "ko": 0 }, "percentiles4": { "total": 425, "ok": 425, "ko": 0 }, "group1": { "name": "t < 800 ms", "count": 14446249, "percentage": 100 }, "group2": { "name": "800 ms < t < 1200 ms", "count": 14692, "percentage": 0 }, "group3": { "name": "t > 1200 ms", "count": 1463, "percentage": 0 }, "group4": { "name": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 3983.0360782153675, "ok": 3983.0360782153675, "ko": 0 } }, "details": [ { "name": "userRestLogin", "numberOfRequests": { "total": 3615601, "ok": 3615601, "ko": 0 }, "minResponseTime": { "total": 10, "ok": 10, "ko": 0 }, "maxResponseTime": { "total": 1034, "ok": 1034, "ko": 0 }, "meanResponseTime": { "total": 35, "ok": 35, "ko": 0 }, "standardDeviation": { "total": 41, "ok": 41, "ko": 0 }, "percentiles1": { "total": 17, "ok": 17, "ko": 0 }, "percentiles2": { "total": 35, "ok": 35, "ko": 0 }, "percentiles3": { "total": 120, "ok": 120, "ko": 0 }, "percentiles4": { "total": 202, "ok": 202, "ko": 0 }, "group1": { "name": "t < 800 ms", "count": 3615452, "percentage": 100 }, "group2": { "name": "800 ms < t < 1200 ms", "count": 149, "percentage": 0 }, "group3": { "name": "t > 1200 ms", "count": 0, "percentage": 0 }, "group4": { "name": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 995.7590195538419, "ok": 995.7590195538419, "ko": 0 }, "spikes": [] }, { "name": "userAuthorize", "numberOfRequests": { "total": 3615601, "ok": 3615601, "ko": 0 }, "minResponseTime": { "total": 3, "ok": 3, "ko": 0 }, "maxResponseTime": { "total": 1019, "ok": 1019, "ko": 0 }, "meanResponseTime": { "total": 23, "ok": 23, "ko": 0 }, "standardDeviation": { "total": 40, "ok": 40, "ko": 0 }, "percentiles1": { "total": 6, "ok": 6, "ko": 0 }, "percentiles2": { "total": 21, "ok": 21, "ko": 0 }, "percentiles3": { "total": 107, "ok": 107, "ko": 0 }, "percentiles4": { "total": 189, "ok": 189, "ko": 0 }, "group1": { "name": "t < 800 ms", "count": 3615510, "percentage": 100 }, "group2": { "name": "800 ms < t < 1200 ms", "count": 91, "percentage": 0 }, "group3": { "name": "t > 1200 ms", "count": 0, "percentage": 0 }, "group4": { "name": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 995.7590195538419, "ok": 995.7590195538419, "ko": 0 }, "spikes": [] }, { "name": "getAccessToken", "numberOfRequests": { "total": 3615601, "ok": 3615601, "ko": 0 }, "minResponseTime": { "total": 5, "ok": 5, "ko": 0 }, "maxResponseTime": { "total": 2283, "ok": 2283, "ko": 0 }, "meanResponseTime": { "total": 98, "ok": 98, "ko": 0 }, "standardDeviation": { "total": 138, "ok": 138, "ko": 0 }, "percentiles1": { "total": 43, "ok": 43, "ko": 0 }, "percentiles2": { "total": 121, "ok": 121, "ko": 0 }, "percentiles3": { "total": 382, "ok": 382, "ko": 0 }, "percentiles4": { "total": 658, "ok": 658, "ko": 0 }, "group1": { "name": "t < 800 ms", "count": 3599686, "percentage": 100 }, "group2": { "name": "800 ms < t < 1200 ms", "count": 14452, "percentage": 0 }, "group3": { "name": "t > 1200 ms", "count": 1463, "percentage": 0 }, "group4": { "name": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 995.7590195538419, "ok": 995.7590195538419, "ko": 0 }, "spikes": [ { "datetime": "2023-02-04T17:22:58", "maxResponseTime": 2283 }, { "datetime": "2023-02-04T17:30:29", "maxResponseTime": 2248 } ] }, { "name": "getTokenInfo", "numberOfRequests": { "total": 3615601, "ok": 3615601, "ko": 0 }, "minResponseTime": { "total": 1, "ok": 1, "ko": 0 }, "maxResponseTime": { "total": 797, "ok": 797, "ko": 0 }, "meanResponseTime": { "total": 19, "ok": 19, "ko": 0 }, "standardDeviation": { "total": 37, "ok": 37, "ko": 0 }, "percentiles1": { "total": 4, "ok": 4, "ko": 0 }, "percentiles2": { "total": 15, "ok": 15, "ko": 0 }, "percentiles3": { "total": 97, "ok": 97, "ko": 0 }, "percentiles4": { "total": 179, "ok": 179, "ko": 0 }, "group1": { "name": "t < 800 ms", "count": 3615601, "percentage": 100 }, "group2": { "name": "800 ms < t < 1200 ms", "count": 0, "percentage": 0 }, "group3": { "name": "t > 1200 ms", "count": 0, "percentage": 0 }, "group4": { "name": "failed", "count": 0, "percentage": 0 }, "meanNumberOfRequestsPerSecond": { "total": 995.7590195538419, "ok": 995.7590195538419, "ko": 0 }, "spikes": [] } ] } { "concurrency": null, "generic_stats": { "avg_num_of_requests_per_second": 3983, "avg_response_time": 44, "max_response_time": 2283, "min_response_time": 1, "num_requests": 14462404, "num_requests_pass": 14462404, "num_requests_percent_pass": 100.0 }, "options": "; rampup-duration:30; simulation:com.forgerock.gatling.Loadtest; testCase:am.AMAccessToken; testMode:open; throughput:1000", "stats": { "details": [ { "group1": { "count": 3615452, "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 149, "name": "800 ms < t < 1200 ms", "percentage": 0 }, "group3": { "count": 0, "name": "t > 1200 ms", "percentage": 0 }, "group4": { "count": 0, "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 1034, "total": 1034 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 995.7590195538419, "total": 995.7590195538419 }, "meanResponseTime": { "ko": 0, "ok": 35, "total": 35 }, "minResponseTime": { "ko": 0, "ok": 10, "total": 10 }, "name": "userRestLogin", "numberOfRequests": { "ko": 0, "ok": 3615601, "total": 3615601 }, "percentiles1": { "ko": 0, "ok": 17, "total": 17 }, "percentiles2": { "ko": 0, "ok": 35, "total": 35 }, "percentiles3": { "ko": 0, "ok": 120, "total": 120 }, "percentiles4": { "ko": 0, "ok": 202, "total": 202 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 41, "total": 41 } }, { "group1": { "count": 3615510, "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 91, "name": "800 ms < t < 1200 ms", "percentage": 0 }, "group3": { "count": 0, "name": "t > 1200 ms", "percentage": 0 }, "group4": { "count": 0, "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 1019, "total": 1019 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 995.7590195538419, "total": 995.7590195538419 }, "meanResponseTime": { "ko": 0, "ok": 23, "total": 23 }, "minResponseTime": { "ko": 0, "ok": 3, "total": 3 }, "name": "userAuthorize", "numberOfRequests": { "ko": 0, "ok": 3615601, "total": 3615601 }, "percentiles1": { "ko": 0, "ok": 6, "total": 6 }, "percentiles2": { "ko": 0, "ok": 21, "total": 21 }, "percentiles3": { "ko": 0, "ok": 107, "total": 107 }, "percentiles4": { "ko": 0, "ok": 189, "total": 189 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 40, "total": 40 } }, { "group1": { "count": 3599686, "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 14452, "name": "800 ms < t < 1200 ms", "percentage": 0 }, "group3": { "count": 1463, "name": "t > 1200 ms", "percentage": 0 }, "group4": { "count": 0, "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 2283, "total": 2283 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 995.7590195538419, "total": 995.7590195538419 }, "meanResponseTime": { "ko": 0, "ok": 98, "total": 98 }, "minResponseTime": { "ko": 0, "ok": 5, "total": 5 }, "name": "getAccessToken", "numberOfRequests": { "ko": 0, "ok": 3615601, "total": 3615601 }, "percentiles1": { "ko": 0, "ok": 43, "total": 43 }, "percentiles2": { "ko": 0, "ok": 121, "total": 121 }, "percentiles3": { "ko": 0, "ok": 382, "total": 382 }, "percentiles4": { "ko": 0, "ok": 658, "total": 658 }, "spikes": [ { "datetime": "2023-02-04T17:22:58", "maxResponseTime": 2283 }, { "datetime": "2023-02-04T17:30:29", "maxResponseTime": 2248 } ], "standardDeviation": { "ko": 0, "ok": 138, "total": 138 } }, { "group1": { "count": 3615601, "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 0, "name": "800 ms < t < 1200 ms", "percentage": 0 }, "group3": { "count": 0, "name": "t > 1200 ms", "percentage": 0 }, "group4": { "count": 0, "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 797, "total": 797 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 995.7590195538419, "total": 995.7590195538419 }, "meanResponseTime": { "ko": 0, "ok": 19, "total": 19 }, "minResponseTime": { "ko": 0, "ok": 1, "total": 1 }, "name": "getTokenInfo", "numberOfRequests": { "ko": 0, "ok": 3615601, "total": 3615601 }, "percentiles1": { "ko": 0, "ok": 4, "total": 4 }, "percentiles2": { "ko": 0, "ok": 15, "total": 15 }, "percentiles3": { "ko": 0, "ok": 97, "total": 97 }, "percentiles4": { "ko": 0, "ok": 179, "total": 179 }, "spikes": [], "standardDeviation": { "ko": 0, "ok": 37, "total": 37 } } ], "global": { "group1": { "count": 14446249, "name": "t < 800 ms", "percentage": 100 }, "group2": { "count": 14692, "name": "800 ms < t < 1200 ms", "percentage": 0 }, "group3": { "count": 1463, "name": "t > 1200 ms", "percentage": 0 }, "group4": { "count": 0, "name": "failed", "percentage": 0 }, "maxResponseTime": { "ko": 0, "ok": 2283, "total": 2283 }, "meanNumberOfRequestsPerSecond": { "ko": 0, "ok": 3983.0360782153675, "total": 3983.0360782153675 }, "meanResponseTime": { "ko": 0, "ok": 44, "total": 44 }, "minResponseTime": { "ko": 0, "ok": 1, "total": 1 }, "name": "Global Information", "numberOfRequests": { "ko": 0, "ok": 14462404, "total": 14462404 }, "percentiles1": { "ko": 0, "ok": 15, "total": 15 }, "percentiles2": { "ko": 0, "ok": 43, "total": 43 }, "percentiles3": { "ko": 0, "ok": 180, "total": 180 }, "percentiles4": { "ko": 0, "ok": 425, "total": 425 }, "standardDeviation": { "ko": 0, "ok": 83, "total": 83 } } }, "target_hostname": "xlou.iam.xlou-bsln.engineeringpit.com", "tool_name": "gatling" } ________________________________________________________________________________ [04/Feb/2023 19:24:10] Reporting_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/13_access-token-1000/stdout.txt Gatling stdout.txt has been deleted ________________________________________________________________________________ [04/Feb/2023 19:24:10] Reporting_access-token-1000 post : Check output ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped