--Task-- name: authn-users-4500 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am 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: ['authn-users-4000'] preceding_task: authn-users-4000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 4500, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-13 00:18:14] authn-users-4500 pre : N/A ________________________________________________________________________________ [2024-04-13 00:18:14] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-13 00:18:14] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-4500/stdout.txt Pod log : /results/orders/authn-users-4500 2024-04-13 00:18:14,397 INFO 2024-04-13 00:18:14,398 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-13 00:18:14,398 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:14,717 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:14,718 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:14,718 DEBUG --- stdout --- 2024-04-13 00:18:14,718 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:14,718 DEBUG --- stderr --- 2024-04-13 00:18:14,718 DEBUG ________________________________________________________________________________ [2024-04-13 00:18:14] authn-users-4500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-13 00:18:14,719 INFO 2024-04-13 00:18:14,719 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-13 00:18:14,719 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:15,050 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:15,050 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:15,050 DEBUG --- stdout --- 2024-04-13 00:18:15,051 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:15,051 DEBUG --- stderr --- 2024-04-13 00:18:15,051 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "am.AMAuthN@medium" --results-folder /results/gatling/authn-users-4500 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-13 00:18:15] authn-users-4500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-13 00:18:15,051 INFO 2024-04-13 00:18:15,052 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-13 00:18:15,052 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:15,396 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:15,397 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:15,397 DEBUG --- stdout --- 2024-04-13 00:18:15,397 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:15,397 DEBUG --- stderr --- 2024-04-13 00:18:15,397 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/order.json { "name": "authn-users-4500", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"am.AMAuthN@medium\" --results-folder /results/gatling/authn-users-4500 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-4500", "environment": { "JAVA_OPTS": "-Dam_host=xlou.iam.xlou-cdm.engineeringpit.com -DamadminPassword=**** -DamadminUsername=amadmin -Dduration=3600 -Dnum_users=1000000 -DtestCase=am.AMAuthN -DtestMode=open -Dthroughput=4500 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-13 00:18:15] authn-users-4500 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-04-13 00:18:15,399 INFO 2024-04-13 00:18:15,399 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-13 00:18:15,400 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-13 00:18:15,648 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 00:18:15,648 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:15,648 DEBUG --- stdout --- 2024-04-13 00:18:15,649 DEBUG WAITING 2024-04-13 00:18:15,649 DEBUG --- stderr --- 2024-04-13 00:18:15,649 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-13 00:18:15] authn-users-4500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-13 00:18:15,650 INFO 2024-04-13 00:18:15,650 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-13 00:18:15,650 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:16,009 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:16,009 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:16,009 DEBUG --- stdout --- 2024-04-13 00:18:16,009 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:16,009 DEBUG --- stderr --- 2024-04-13 00:18:16,009 DEBUG 2024-04-13 00:18:16,009 INFO 2024-04-13 00:18:16,009 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/07_Scenario_AuthN/04_authn-users-4500/order.json overseer-0-59d79dcbf-spbbl:/results/orders/order.json 2024-04-13 00:18:16,009 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 00:18:17,375 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:17,375 DEBUG --- stdout --- 2024-04-13 00:18:17,375 DEBUG 2024-04-13 00:18:17,375 DEBUG --- stderr --- 2024-04-13 00:18:17,375 DEBUG Order has been successfully forwarded to overseer [2024-04-13 00:18:17] - INFO: Check if Gatling simulation started successfully 2024-04-13 00:18:17,376 INFO 2024-04-13 00:18:17,376 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-13 00:18:17,376 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:17,681 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:17,681 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:17,681 DEBUG --- stdout --- 2024-04-13 00:18:17,681 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:17,681 DEBUG --- stderr --- 2024-04-13 00:18:17,681 DEBUG 2024-04-13 00:18:17,682 INFO 2024-04-13 00:18:17,682 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl -- head -n 25 /results/orders/authn-users-4500/stdout.txt | grep "started..." 2024-04-13 00:18:17,682 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-13 00:18:18,463 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-13 00:18:24,219 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-13 00:18:29,995 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-13 00:18:29,995 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:29,995 DEBUG --- stdout --- 2024-04-13 00:18:29,995 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... ================================================================================ 2024-04-13 01:18:29 5s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=1873 KO=0 ) > userRestLogin (OK=1873 KO=0 ) ---- am.AMAuthN ---------------------------------------------------------------- [- ] 0% waiting: 16266201 / active: 11 / done: 1873 ================================================================================ 2024-04-13 00:18:29,995 DEBUG --- stderr --- 2024-04-13 00:18:29,995 DEBUG ________________________________________________________________________________ [2024-04-13 00:18:29] authn-users-4500 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-13 01:20:40] authn-users-4500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-13 01:20:40,582 INFO 2024-04-13 01:20:40,582 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-13 01:20:40,582 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:40,908 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:40,908 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:40,909 DEBUG --- stdout --- 2024-04-13 01:20:40,909 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:40,909 DEBUG --- stderr --- 2024-04-13 01:20:40,909 DEBUG 2024-04-13 01:20:40,909 INFO 2024-04-13 01:20:40,909 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl -- rm -f /results/orders/order.json 2024-04-13 01:20:40,909 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 01:20:41,716 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:41,716 DEBUG --- stdout --- 2024-04-13 01:20:41,716 DEBUG 2024-04-13 01:20:41,716 DEBUG --- stderr --- 2024-04-13 01:20:41,716 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-13 01:20:41] authn-users-4500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-13 01:20:41] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/order.json 2024-04-13 01:20:41,717 INFO 2024-04-13 01:20:41,717 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-13 01:20:41,717 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:42,085 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:42,087 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:42,087 DEBUG --- stdout --- 2024-04-13 01:20:42,087 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:42,087 DEBUG --- stderr --- 2024-04-13 01:20:42,087 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 01:20:42,087 INFO 2024-04-13 01:20:42,087 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-13 01:20:42,087 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:42,455 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:42,455 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:42,455 DEBUG --- stdout --- 2024-04-13 01:20:42,456 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:42,456 DEBUG --- stderr --- 2024-04-13 01:20:42,456 DEBUG 2024-04-13 01:20:42,456 INFO 2024-04-13 01:20:42,456 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4500/rc.txt" ]; then echo "/results/orders/authn-users-4500/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-4500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4500/rc.txt file found" 2024-04-13 01:20:42,456 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 01:20:43,320 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 01:20:43,321 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:43,321 DEBUG --- stdout --- 2024-04-13 01:20:43,321 DEBUG /results/orders/authn-users-4500/rc.txt file found 2024-04-13 01:20:43,321 DEBUG --- stderr --- 2024-04-13 01:20:43,321 DEBUG 2024-04-13 01:20:43,321 INFO 2024-04-13 01:20:43,321 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-13 01:20:43,321 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:43,624 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:43,624 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:43,624 DEBUG --- stdout --- 2024-04-13 01:20:43,624 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:43,624 DEBUG --- stderr --- 2024-04-13 01:20:43,624 DEBUG 2024-04-13 01:20:43,625 INFO 2024-04-13 01:20:43,625 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-4500/rc.txt 2024-04-13 01:20:44,496 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 01:20:44,497 DEBUG --- stdout --- 2024-04-13 01:20:44,497 DEBUG 4 /results/orders/authn-users-4500/rc.txt 2024-04-13 01:20:44,497 DEBUG --- stderr --- 2024-04-13 01:20:44,497 DEBUG 2024-04-13 01:20:44,497 INFO - Download file (size 4k) 2024-04-13 01:20:44,497 INFO 2024-04-13 01:20:44,497 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-13 01:20:44,497 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:44,815 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:44,815 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:44,815 DEBUG --- stdout --- 2024-04-13 01:20:44,815 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:44,816 DEBUG --- stderr --- 2024-04-13 01:20:44,816 DEBUG source : /results/orders/authn-users-4500/rc.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/rc.txt 2024-04-13 01:20:44,816 INFO 2024-04-13 01:20:44,816 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-13 01:20:44,816 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:45,138 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:45,138 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:45,138 DEBUG --- stdout --- 2024-04-13 01:20:45,138 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:45,138 DEBUG --- stderr --- 2024-04-13 01:20:45,138 DEBUG 2024-04-13 01:20:45,139 INFO 2024-04-13 01:20:45,139 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-4500/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/rc.txt 2024-04-13 01:20:45,139 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 01:20:45,892 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:45,893 DEBUG --- stdout --- 2024-04-13 01:20:45,893 DEBUG tar: Removing leading `/' from member names 2024-04-13 01:20:45,893 DEBUG --- stderr --- 2024-04-13 01:20:45,893 DEBUG 2024-04-13 01:20:45,894 INFO 2024-04-13 01:20:45,894 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-13 01:20:45,894 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:46,208 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:46,209 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:46,209 DEBUG --- stdout --- 2024-04-13 01:20:46,209 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:46,209 DEBUG --- stderr --- 2024-04-13 01:20:46,209 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 01:20:46,209 INFO 2024-04-13 01:20:46,209 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-13 01:20:46,209 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:46,563 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:46,563 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:46,563 DEBUG --- stdout --- 2024-04-13 01:20:46,563 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:46,563 DEBUG --- stderr --- 2024-04-13 01:20:46,563 DEBUG 2024-04-13 01:20:46,564 INFO 2024-04-13 01:20:46,564 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4500/stderr.txt" ]; then echo "/results/orders/authn-users-4500/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-4500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4500/stderr.txt file found" 2024-04-13 01:20:46,564 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 01:20:47,442 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 01:20:47,444 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:47,444 DEBUG --- stdout --- 2024-04-13 01:20:47,444 DEBUG /results/orders/authn-users-4500/stderr.txt file found 2024-04-13 01:20:47,445 DEBUG --- stderr --- 2024-04-13 01:20:47,445 DEBUG 2024-04-13 01:20:47,445 INFO 2024-04-13 01:20:47,445 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-13 01:20:47,445 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:47,818 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:47,818 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:47,818 DEBUG --- stdout --- 2024-04-13 01:20:47,818 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:47,819 DEBUG --- stderr --- 2024-04-13 01:20:47,819 DEBUG 2024-04-13 01:20:47,819 INFO 2024-04-13 01:20:47,819 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-4500/stderr.txt 2024-04-13 01:20:48,702 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 01:20:48,702 DEBUG --- stdout --- 2024-04-13 01:20:48,702 DEBUG 0 /results/orders/authn-users-4500/stderr.txt 2024-04-13 01:20:48,702 DEBUG --- stderr --- 2024-04-13 01:20:48,703 DEBUG 2024-04-13 01:20:48,703 INFO - Download file (size 0k) 2024-04-13 01:20:48,703 INFO 2024-04-13 01:20:48,703 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-13 01:20:48,703 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:49,018 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:49,018 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:49,018 DEBUG --- stdout --- 2024-04-13 01:20:49,018 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:49,018 DEBUG --- stderr --- 2024-04-13 01:20:49,018 DEBUG source : /results/orders/authn-users-4500/stderr.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/stderr.txt 2024-04-13 01:20:49,018 INFO 2024-04-13 01:20:49,018 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-13 01:20:49,018 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:49,321 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:49,322 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:49,322 DEBUG --- stdout --- 2024-04-13 01:20:49,322 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:49,322 DEBUG --- stderr --- 2024-04-13 01:20:49,322 DEBUG 2024-04-13 01:20:49,322 INFO 2024-04-13 01:20:49,322 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-4500/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/stderr.txt 2024-04-13 01:20:49,322 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 01:20:50,093 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:50,093 DEBUG --- stdout --- 2024-04-13 01:20:50,093 DEBUG tar: Removing leading `/' from member names 2024-04-13 01:20:50,094 DEBUG --- stderr --- 2024-04-13 01:20:50,094 DEBUG 2024-04-13 01:20:50,094 INFO 2024-04-13 01:20:50,094 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-13 01:20:50,094 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:50,397 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:50,397 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:50,397 DEBUG --- stdout --- 2024-04-13 01:20:50,397 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:50,397 DEBUG --- stderr --- 2024-04-13 01:20:50,397 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 01:20:50,397 INFO 2024-04-13 01:20:50,397 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-13 01:20:50,398 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:50,702 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:50,702 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:50,702 DEBUG --- stdout --- 2024-04-13 01:20:50,702 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:50,702 DEBUG --- stderr --- 2024-04-13 01:20:50,702 DEBUG 2024-04-13 01:20:50,703 INFO 2024-04-13 01:20:50,703 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4500/stdout.txt" ]; then echo "/results/orders/authn-users-4500/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-4500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4500/stdout.txt file found" 2024-04-13 01:20:50,703 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 01:20:51,520 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 01:20:51,520 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:51,520 DEBUG --- stdout --- 2024-04-13 01:20:51,520 DEBUG /results/orders/authn-users-4500/stdout.txt file found 2024-04-13 01:20:51,520 DEBUG --- stderr --- 2024-04-13 01:20:51,520 DEBUG 2024-04-13 01:20:51,520 INFO 2024-04-13 01:20:51,520 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-13 01:20:51,520 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:51,830 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:51,830 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:51,831 DEBUG --- stdout --- 2024-04-13 01:20:51,831 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:51,831 DEBUG --- stderr --- 2024-04-13 01:20:51,831 DEBUG 2024-04-13 01:20:51,831 INFO 2024-04-13 01:20:51,831 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-4500/stdout.txt 2024-04-13 01:20:52,689 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 01:20:52,691 DEBUG --- stdout --- 2024-04-13 01:20:52,691 DEBUG 556 /results/orders/authn-users-4500/stdout.txt 2024-04-13 01:20:52,691 DEBUG --- stderr --- 2024-04-13 01:20:52,691 DEBUG 2024-04-13 01:20:52,691 INFO - Download file (size 556k) 2024-04-13 01:20:52,692 INFO 2024-04-13 01:20:52,692 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-13 01:20:52,692 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:52,994 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:52,994 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:52,994 DEBUG --- stdout --- 2024-04-13 01:20:52,995 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:52,995 DEBUG --- stderr --- 2024-04-13 01:20:52,995 DEBUG source : /results/orders/authn-users-4500/stdout.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/stdout.txt 2024-04-13 01:20:52,995 INFO 2024-04-13 01:20:52,995 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-13 01:20:52,995 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:53,315 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:53,315 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:53,315 DEBUG --- stdout --- 2024-04-13 01:20:53,315 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:53,315 DEBUG --- stderr --- 2024-04-13 01:20:53,315 DEBUG 2024-04-13 01:20:53,316 INFO 2024-04-13 01:20:53,316 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-4500/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/stdout.txt 2024-04-13 01:20:53,316 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 01:20:54,429 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:54,429 DEBUG --- stdout --- 2024-04-13 01:20:54,429 DEBUG tar: Removing leading `/' from member names 2024-04-13 01:20:54,429 DEBUG --- stderr --- 2024-04-13 01:20:54,429 DEBUG ________________________________________________________________________________ [2024-04-13 01:20:54] authn-users-4500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-13 01:20:54,430 INFO 2024-04-13 01:20:54,430 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-13 01:20:54,430 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:54,744 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:54,744 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:54,744 DEBUG --- stdout --- 2024-04-13 01:20:54,744 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:54,744 DEBUG --- stderr --- 2024-04-13 01:20:54,744 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 01:20:54,745 INFO 2024-04-13 01:20:54,745 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-13 01:20:54,745 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:55,048 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:55,048 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:55,048 DEBUG --- stdout --- 2024-04-13 01:20:55,048 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:55,048 DEBUG --- stderr --- 2024-04-13 01:20:55,048 DEBUG 2024-04-13 01:20:55,049 INFO 2024-04-13 01:20:55,049 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4500/cmd.txt" ]; then echo "/results/orders/authn-users-4500/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-4500/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4500/cmd.txt file found" 2024-04-13 01:20:55,049 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 01:20:55,866 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 01:20:55,866 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:55,866 DEBUG --- stdout --- 2024-04-13 01:20:55,866 DEBUG /results/orders/authn-users-4500/cmd.txt file found 2024-04-13 01:20:55,867 DEBUG --- stderr --- 2024-04-13 01:20:55,867 DEBUG 2024-04-13 01:20:55,867 INFO 2024-04-13 01:20:55,867 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-13 01:20:55,867 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:56,185 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:56,186 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:56,186 DEBUG --- stdout --- 2024-04-13 01:20:56,186 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:56,186 DEBUG --- stderr --- 2024-04-13 01:20:56,186 DEBUG 2024-04-13 01:20:56,186 INFO 2024-04-13 01:20:56,186 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-4500/cmd.txt 2024-04-13 01:20:57,027 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 01:20:57,027 DEBUG --- stdout --- 2024-04-13 01:20:57,027 DEBUG 4 /results/orders/authn-users-4500/cmd.txt 2024-04-13 01:20:57,027 DEBUG --- stderr --- 2024-04-13 01:20:57,027 DEBUG 2024-04-13 01:20:57,027 INFO - Download file (size 4k) 2024-04-13 01:20:57,028 INFO 2024-04-13 01:20:57,028 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-13 01:20:57,028 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:57,341 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:57,341 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:57,341 DEBUG --- stdout --- 2024-04-13 01:20:57,341 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:57,341 DEBUG --- stderr --- 2024-04-13 01:20:57,341 DEBUG source : /results/orders/authn-users-4500/cmd.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/cmd.txt 2024-04-13 01:20:57,342 INFO 2024-04-13 01:20:57,342 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-13 01:20:57,342 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:57,661 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:57,662 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:57,662 DEBUG --- stdout --- 2024-04-13 01:20:57,662 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:57,662 DEBUG --- stderr --- 2024-04-13 01:20:57,662 DEBUG 2024-04-13 01:20:57,663 INFO 2024-04-13 01:20:57,663 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-4500/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/cmd.txt 2024-04-13 01:20:57,663 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 01:20:58,453 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:58,453 DEBUG --- stdout --- 2024-04-13 01:20:58,453 DEBUG tar: Removing leading `/' from member names 2024-04-13 01:20:58,453 DEBUG --- stderr --- 2024-04-13 01:20:58,453 DEBUG 2024-04-13 01:20:58,453 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/cmd.txt is small (1 lines), printing all lines: 2024-04-13 01:20:58,453 DEBUG ----- output ----- 2024-04-13 01:20:58,453 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "am.AMAuthN@medium" --results-folder /results/gatling/authn-users-4500 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-13 01:20:58,453 INFO ________________________________________________________________________________ [2024-04-13 01:20:58] authn-users-4500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-13 01:20:58,454 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/rc.txt is small (1 lines), printing all lines: 2024-04-13 01:20:58,454 DEBUG ----- output ----- 2024-04-13 01:20:58,454 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-13 01:20:58,454 INFO ________________________________________________________________________________ [2024-04-13 01:20:58] authn-users-4500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-13 01:20:58,456 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/stdout.txt is 9574 lines): 2024-04-13 01:20:58,456 DEBUG ----- output ----- 2024-04-13 01:20:58,456 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-13 01:20:58,456 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-13 01:20:58,456 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-13 01:20:58,456 INFO 2024-04-13 01:20:58,456 INFO ================================================================================ 2024-04-13 01:20:58,456 INFO 2024-04-13 01:18:29 5s elapsed 2024-04-13 01:20:58,456 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,456 INFO > Global (OK=1873 KO=0 ) 2024-04-13 01:20:58,456 INFO > userRestLogin (OK=1873 KO=0 ) 2024-04-13 01:20:58,456 INFO 2024-04-13 01:20:58,456 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,456 INFO [- ] 0% 2024-04-13 01:20:58,456 INFO waiting: 16266201 / active: 11 / done: 1873 2024-04-13 01:20:58,456 INFO ================================================================================ 2024-04-13 01:20:58,456 INFO 2024-04-13 01:20:58,456 INFO 2024-04-13 01:20:58,456 INFO ================================================================================ 2024-04-13 01:20:58,456 INFO 2024-04-13 01:18:34 10s elapsed 2024-04-13 01:20:58,456 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,456 INFO > Global (OK=7478 KO=0 ) 2024-04-13 01:20:58,456 INFO > userRestLogin (OK=7478 KO=0 ) 2024-04-13 01:20:58,456 INFO 2024-04-13 01:20:58,456 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,456 INFO [- ] 0% 2024-04-13 01:20:58,456 INFO waiting: 16260567 / active: 40 / done: 7478 2024-04-13 01:20:58,456 INFO ================================================================================ 2024-04-13 01:20:58,456 INFO 2024-04-13 01:20:58,456 INFO 2024-04-13 01:20:58,456 INFO ================================================================================ 2024-04-13 01:20:58,456 INFO 2024-04-13 01:18:39 15s elapsed 2024-04-13 01:20:58,456 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,456 INFO > Global (OK=16867 KO=0 ) 2024-04-13 01:20:58,456 INFO > userRestLogin (OK=16867 KO=0 ) 2024-04-13 01:20:58,456 INFO 2024-04-13 01:20:58,456 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,457 INFO [- ] 0% 2024-04-13 01:20:58,457 INFO waiting: 16251184 / active: 34 / done: 16867 2024-04-13 01:20:58,457 INFO ================================================================================ 2024-04-13 01:20:58,457 INFO 2024-04-13 01:20:58,457 INFO 2024-04-13 01:20:58,457 INFO ================================================================================ 2024-04-13 01:20:58,457 INFO 2024-04-13 01:18:44 20s elapsed 2024-04-13 01:20:58,457 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,457 INFO > Global (OK=29985 KO=0 ) 2024-04-13 01:20:58,457 INFO > userRestLogin (OK=29985 KO=0 ) 2024-04-13 01:20:58,457 INFO 2024-04-13 01:20:58,457 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,457 INFO [- ] 0% 2024-04-13 01:20:58,457 INFO waiting: 16238054 / active: 46 / done: 29985 2024-04-13 01:20:58,457 INFO ================================================================================ 2024-04-13 01:20:58,457 INFO 2024-04-13 01:20:58,457 INFO 2024-04-13 01:20:58,457 INFO ================================================================================ 2024-04-13 01:20:58,457 INFO 2024-04-13 01:18:49 25s elapsed 2024-04-13 01:20:58,457 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,457 INFO > Global (OK=46859 KO=0 ) 2024-04-13 01:20:58,457 INFO > userRestLogin (OK=46859 KO=0 ) 2024-04-13 01:20:58,457 INFO 2024-04-13 01:20:58,457 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,457 INFO [- ] 0% 2024-04-13 01:20:58,457 INFO waiting: 16221171 / active: 56 / done: 46858 2024-04-13 01:20:58,457 INFO ================================================================================ 2024-04-13 01:20:58,457 INFO 2024-04-13 01:20:58,457 INFO 2024-04-13 01:20:58,457 INFO ================================================================================ 2024-04-13 01:20:58,457 INFO 2024-04-13 01:18:54 30s elapsed 2024-04-13 01:20:58,457 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,457 INFO > Global (OK=67336 KO=0 ) 2024-04-13 01:20:58,457 INFO > userRestLogin (OK=67336 KO=0 ) 2024-04-13 01:20:58,457 INFO 2024-04-13 01:20:58,457 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,457 INFO [- ] 0% 2024-04-13 01:20:58,457 INFO waiting: 16200657 / active: 92 / done: 67336 2024-04-13 01:20:58,457 INFO ================================================================================ 2024-04-13 01:20:58,457 INFO 2024-04-13 01:20:58,457 INFO 2024-04-13 01:20:58,458 INFO ================================================================================ 2024-04-13 01:20:58,458 INFO 2024-04-13 01:18:59 35s elapsed 2024-04-13 01:20:58,458 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,458 INFO > Global (OK=90005 KO=0 ) 2024-04-13 01:20:58,458 INFO > userRestLogin (OK=90005 KO=0 ) 2024-04-13 01:20:58,458 INFO 2024-04-13 01:20:58,458 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,458 INFO [- ] 0% 2024-04-13 01:20:58,458 INFO waiting: 16178002 / active: 78 / done: 90005 2024-04-13 01:20:58,458 INFO ================================================================================ 2024-04-13 01:20:58,458 INFO 2024-04-13 01:20:58,458 INFO 2024-04-13 01:20:58,458 INFO ================================================================================ 2024-04-13 01:20:58,458 INFO 2024-04-13 01:19:04 40s elapsed 2024-04-13 01:20:58,458 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,458 INFO > Global (OK=112513 KO=0 ) 2024-04-13 01:20:58,458 INFO > userRestLogin (OK=112513 KO=0 ) 2024-04-13 01:20:58,458 INFO 2024-04-13 01:20:58,458 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,458 INFO [- ] 0% 2024-04-13 01:20:58,458 INFO waiting: 16155488 / active: 85 / done: 112512 2024-04-13 01:20:58,458 INFO ================================================================================ 2024-04-13 01:20:58,458 INFO 2024-04-13 01:20:58,458 INFO 2024-04-13 01:20:58,458 INFO [...] 2024-04-13 01:20:58,461 INFO 2024-04-13 01:20:58,461 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,461 INFO [#########################################################################-] 99% 2024-04-13 01:20:58,461 INFO waiting: 89823 / active: 516 / done: 16177746 2024-04-13 01:20:58,461 INFO ================================================================================ 2024-04-13 01:20:58,461 INFO 2024-04-13 01:20:58,461 INFO 2024-04-13 01:20:58,461 INFO ================================================================================ 2024-04-13 01:20:58,461 INFO 2024-04-13 02:18:39 3615s elapsed 2024-04-13 01:20:58,461 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,461 INFO > Global (OK=16200647 KO=3 ) 2024-04-13 01:20:58,461 INFO > userRestLogin (OK=16200647 KO=3 ) 2024-04-13 01:20:58,461 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 01:20:58,461 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 3 (100.0%) 2024-04-13 01:20:58,461 INFO ), but actually found 502 2024-04-13 01:20:58,461 INFO 2024-04-13 01:20:58,461 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,461 INFO [#########################################################################-] 99% 2024-04-13 01:20:58,461 INFO waiting: 67354 / active: 82 / done: 16200649 2024-04-13 01:20:58,461 INFO ================================================================================ 2024-04-13 01:20:58,461 INFO 2024-04-13 01:20:58,461 INFO 2024-04-13 01:20:58,461 INFO ================================================================================ 2024-04-13 01:20:58,461 INFO 2024-04-13 02:18:44 3620s elapsed 2024-04-13 01:20:58,461 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,462 INFO > Global (OK=16222776 KO=3 ) 2024-04-13 01:20:58,462 INFO > userRestLogin (OK=16222776 KO=3 ) 2024-04-13 01:20:58,462 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 01:20:58,462 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 3 (100.0%) 2024-04-13 01:20:58,462 INFO ), but actually found 502 2024-04-13 01:20:58,462 INFO 2024-04-13 01:20:58,462 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,462 INFO [#########################################################################-] 99% 2024-04-13 01:20:58,462 INFO waiting: 45089 / active: 218 / done: 16222778 2024-04-13 01:20:58,462 INFO ================================================================================ 2024-04-13 01:20:58,462 INFO 2024-04-13 01:20:58,462 INFO 2024-04-13 01:20:58,462 INFO ================================================================================ 2024-04-13 01:20:58,462 INFO 2024-04-13 02:18:49 3625s elapsed 2024-04-13 01:20:58,462 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,462 INFO > Global (OK=16245375 KO=3 ) 2024-04-13 01:20:58,462 INFO > userRestLogin (OK=16245375 KO=3 ) 2024-04-13 01:20:58,462 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 01:20:58,462 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 3 (100.0%) 2024-04-13 01:20:58,462 INFO ), but actually found 502 2024-04-13 01:20:58,462 INFO 2024-04-13 01:20:58,462 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,462 INFO [#########################################################################-] 99% 2024-04-13 01:20:58,462 INFO waiting: 22621 / active: 89 / done: 16245375 2024-04-13 01:20:58,462 INFO ================================================================================ 2024-04-13 01:20:58,462 INFO 2024-04-13 01:20:58,462 INFO 2024-04-13 01:20:58,462 INFO ================================================================================ 2024-04-13 01:20:58,462 INFO 2024-04-13 02:18:54 3630s elapsed 2024-04-13 01:20:58,462 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,462 INFO > Global (OK=16268029 KO=3 ) 2024-04-13 01:20:58,462 INFO > userRestLogin (OK=16268029 KO=3 ) 2024-04-13 01:20:58,462 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 01:20:58,462 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 3 (100.0%) 2024-04-13 01:20:58,462 INFO ), but actually found 502 2024-04-13 01:20:58,462 INFO 2024-04-13 01:20:58,462 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,463 INFO [#########################################################################-] 99% 2024-04-13 01:20:58,463 INFO waiting: 0 / active: 53 / done: 16268032 2024-04-13 01:20:58,463 INFO ================================================================================ 2024-04-13 01:20:58,463 INFO 2024-04-13 01:20:58,463 INFO 2024-04-13 01:20:58,463 INFO ================================================================================ 2024-04-13 01:20:58,463 INFO 2024-04-13 02:18:54 3630s elapsed 2024-04-13 01:20:58,463 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 01:20:58,463 INFO > Global (OK=16268082 KO=3 ) 2024-04-13 01:20:58,463 INFO > userRestLogin (OK=16268082 KO=3 ) 2024-04-13 01:20:58,463 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 01:20:58,463 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 3 (100.0%) 2024-04-13 01:20:58,463 INFO ), but actually found 502 2024-04-13 01:20:58,463 INFO 2024-04-13 01:20:58,463 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 01:20:58,463 INFO [##########################################################################]100% 2024-04-13 01:20:58,463 INFO waiting: 0 / active: 0 / done: 16268085 2024-04-13 01:20:58,463 INFO ================================================================================ 2024-04-13 01:20:58,463 INFO 2024-04-13 01:20:58,463 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-13 01:20:58,463 INFO 2024-04-13 01:20:58,463 INFO ---------------------- 2024-04-13 01:20:58,463 INFO [13/Apr/2024 02:18:54] Create tar file authn-users-4500.tar.gz 2024-04-13 01:20:58,463 INFO ---------------------- 2024-04-13 01:20:58,463 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-13 01:20:58,463 INFO RESULT_FOLDER=authn-users-4500 2024-04-13 01:20:58,463 INFO SIMULATION_PATH=authn-users-4500/loadtest-20240413001824074 2024-04-13 01:20:58,463 INFO PWD=/results/gatling/authn-users-4500/loadtest-20240413001824074 2024-04-13 01:20:58,463 INFO [cmd] tar -czvf /results/gatling//authn-users-4500.tar.gz --exclude=simulation.log * 2024-04-13 01:20:58,463 INFO /results/gatling 2024-04-13 01:20:58,463 INFO 2024-04-13 01:20:58,463 INFO ---------------------- 2024-04-13 01:20:58,463 INFO [13/Apr/2024 02:18:54] Update /results/gatling//latest 2024-04-13 01:20:58,463 INFO ---------------------- 2024-04-13 01:20:58,463 INFO 2024-04-13 01:20:58,463 INFO ---------------------- 2024-04-13 01:20:58,463 INFO [13/Apr/2024 02:18:54] Update /results/gatling//latest.tar.gz 2024-04-13 01:20:58,463 INFO ---------------------- 2024-04-13 01:20:58,463 INFO ________________________________________________________________________________ [2024-04-13 01:20:58] authn-users-4500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-13 01:20:58,464 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4500/stderr.txt is small (0 lines), printing all lines: 2024-04-13 01:20:58,464 DEBUG ----- output ----- 2024-04-13 01:20:58,464 DEBUG 2024-04-13 01:20:58,464 INFO ________________________________________________________________________________ [2024-04-13 01:20:58] authn-users-4500 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-13 01:20:58] authn-users-4500 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-13 01:20:58] authn-users-4500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped