--Task-- name: authn-users-5000 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-4500'] preceding_task: authn-users-4500 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 5000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-13 01:20:59] authn-users-5000 pre : N/A ________________________________________________________________________________ [2024-04-13 01:20:59] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-13 01:20:59] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-5000/stdout.txt Pod log : /results/orders/authn-users-5000 2024-04-13 01:20:59,370 INFO 2024-04-13 01:20:59,370 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:59,370 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:20:59,771 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:20:59,771 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:20:59,772 DEBUG --- stdout --- 2024-04-13 01:20:59,772 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:20:59,772 DEBUG --- stderr --- 2024-04-13 01:20:59,772 DEBUG ________________________________________________________________________________ [2024-04-13 01:20:59] authn-users-5000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-13 01:20:59,773 INFO 2024-04-13 01:20:59,774 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:59,774 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:21:00,133 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:21:00,133 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:21:00,133 DEBUG --- stdout --- 2024-04-13 01:21:00,133 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:21:00,133 DEBUG --- stderr --- 2024-04-13 01:21:00,133 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-5000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-13 01:21:00] authn-users-5000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-13 01:21:00,134 INFO 2024-04-13 01:21:00,134 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:21:00,135 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:21:00,486 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:21:00,486 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:21:00,486 DEBUG --- stdout --- 2024-04-13 01:21:00,487 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:21:00,487 DEBUG --- stderr --- 2024-04-13 01:21:00,487 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/order.json { "name": "authn-users-5000", "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-5000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-5000", "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=5000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-13 01:21:00] authn-users-5000 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 01:21:00,488 INFO 2024-04-13 01:21:00,488 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-13 01:21:00,488 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-13 01:21:00,721 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 01:21:00,722 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:21:00,722 DEBUG --- stdout --- 2024-04-13 01:21:00,722 DEBUG WAITING 2024-04-13 01:21:00,722 DEBUG --- stderr --- 2024-04-13 01:21:00,722 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-13 01:21:00] authn-users-5000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-13 01:21:00,723 INFO 2024-04-13 01:21:00,723 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:21:00,723 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:21:01,023 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:21:01,023 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:21:01,023 DEBUG --- stdout --- 2024-04-13 01:21:01,023 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:21:01,023 DEBUG --- stderr --- 2024-04-13 01:21:01,023 DEBUG 2024-04-13 01:21:01,023 INFO 2024-04-13 01:21:01,023 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/05_authn-users-5000/order.json overseer-0-59d79dcbf-spbbl:/results/orders/order.json 2024-04-13 01:21:01,023 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 01:21:02,404 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:21:02,404 DEBUG --- stdout --- 2024-04-13 01:21:02,404 DEBUG 2024-04-13 01:21:02,404 DEBUG --- stderr --- 2024-04-13 01:21:02,404 DEBUG Order has been successfully forwarded to overseer [2024-04-13 01:21:02] - INFO: Check if Gatling simulation started successfully 2024-04-13 01:21:02,405 INFO 2024-04-13 01:21:02,405 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:21:02,405 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 01:21:02,721 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 01:21:02,721 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:21:02,721 DEBUG --- stdout --- 2024-04-13 01:21:02,721 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 01:21:02,721 DEBUG --- stderr --- 2024-04-13 01:21:02,721 DEBUG 2024-04-13 01:21:02,721 INFO 2024-04-13 01:21:02,721 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-5000/stdout.txt | grep "started..." 2024-04-13 01:21:02,721 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-13 01:21:03,486 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-13 01:21:09,270 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-13 01:21:15,049 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-13 01:21:15,049 INFO [loop_until]: OK (rc = 0) 2024-04-13 01:21:15,049 DEBUG --- stdout --- 2024-04-13 01:21:15,049 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 02:21:14 5s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=2085 KO=0 ) > userRestLogin (OK=2085 KO=0 ) ---- am.AMAuthN ---------------------------------------------------------------- [- ] 0% waiting: 18077173 / active: 11 / done: 2084 ================================================================================ 2024-04-13 01:21:15,049 DEBUG --- stderr --- 2024-04-13 01:21:15,049 DEBUG ________________________________________________________________________________ [2024-04-13 01:21:15] authn-users-5000 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, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, 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 02:23:25] authn-users-5000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-13 02:23:25,416 INFO 2024-04-13 02:23:25,416 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 02:23:25,416 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:25,763 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:25,764 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:25,764 DEBUG --- stdout --- 2024-04-13 02:23:25,764 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:25,764 DEBUG --- stderr --- 2024-04-13 02:23:25,764 DEBUG 2024-04-13 02:23:25,764 INFO 2024-04-13 02:23:25,764 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 02:23:25,764 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 02:23:26,587 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:26,587 DEBUG --- stdout --- 2024-04-13 02:23:26,587 DEBUG 2024-04-13 02:23:26,587 DEBUG --- stderr --- 2024-04-13 02:23:26,587 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-13 02:23:26] authn-users-5000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-13 02:23:26] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/order.json 2024-04-13 02:23:26,588 INFO 2024-04-13 02:23:26,588 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 02:23:26,588 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:26,893 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:26,894 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:26,894 DEBUG --- stdout --- 2024-04-13 02:23:26,894 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:26,894 DEBUG --- stderr --- 2024-04-13 02:23:26,894 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 02:23:26,894 INFO 2024-04-13 02:23:26,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 02:23:26,894 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:27,194 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:27,194 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:27,194 DEBUG --- stdout --- 2024-04-13 02:23:27,194 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:27,194 DEBUG --- stderr --- 2024-04-13 02:23:27,194 DEBUG 2024-04-13 02:23:27,195 INFO 2024-04-13 02:23:27,196 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-5000/rc.txt" ]; then echo "/results/orders/authn-users-5000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-5000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5000/rc.txt file found" 2024-04-13 02:23:27,196 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 02:23:28,021 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 02:23:28,021 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:28,021 DEBUG --- stdout --- 2024-04-13 02:23:28,021 DEBUG /results/orders/authn-users-5000/rc.txt file found 2024-04-13 02:23:28,021 DEBUG --- stderr --- 2024-04-13 02:23:28,021 DEBUG 2024-04-13 02:23:28,021 INFO 2024-04-13 02:23:28,021 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 02:23:28,021 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:28,326 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:28,326 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:28,326 DEBUG --- stdout --- 2024-04-13 02:23:28,326 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:28,326 DEBUG --- stderr --- 2024-04-13 02:23:28,327 DEBUG 2024-04-13 02:23:28,327 INFO 2024-04-13 02:23:28,327 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-5000/rc.txt 2024-04-13 02:23:29,184 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 02:23:29,185 DEBUG --- stdout --- 2024-04-13 02:23:29,185 DEBUG 4 /results/orders/authn-users-5000/rc.txt 2024-04-13 02:23:29,185 DEBUG --- stderr --- 2024-04-13 02:23:29,185 DEBUG 2024-04-13 02:23:29,185 INFO - Download file (size 4k) 2024-04-13 02:23:29,185 INFO 2024-04-13 02:23:29,185 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 02:23:29,185 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:29,483 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:29,484 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:29,484 DEBUG --- stdout --- 2024-04-13 02:23:29,484 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:29,484 DEBUG --- stderr --- 2024-04-13 02:23:29,484 DEBUG source : /results/orders/authn-users-5000/rc.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/rc.txt 2024-04-13 02:23:29,484 INFO 2024-04-13 02:23:29,484 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 02:23:29,484 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:29,787 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:29,787 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:29,787 DEBUG --- stdout --- 2024-04-13 02:23:29,787 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:29,787 DEBUG --- stderr --- 2024-04-13 02:23:29,788 DEBUG 2024-04-13 02:23:29,788 INFO 2024-04-13 02:23:29,788 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-5000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/rc.txt 2024-04-13 02:23:29,788 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 02:23:30,561 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:30,561 DEBUG --- stdout --- 2024-04-13 02:23:30,561 DEBUG tar: Removing leading `/' from member names 2024-04-13 02:23:30,561 DEBUG --- stderr --- 2024-04-13 02:23:30,562 DEBUG 2024-04-13 02:23:30,562 INFO 2024-04-13 02:23:30,562 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 02:23:30,562 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:30,863 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:30,863 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:30,863 DEBUG --- stdout --- 2024-04-13 02:23:30,863 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:30,863 DEBUG --- stderr --- 2024-04-13 02:23:30,863 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 02:23:30,864 INFO 2024-04-13 02:23:30,864 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 02:23:30,864 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:31,169 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:31,169 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:31,170 DEBUG --- stdout --- 2024-04-13 02:23:31,170 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:31,170 DEBUG --- stderr --- 2024-04-13 02:23:31,170 DEBUG 2024-04-13 02:23:31,170 INFO 2024-04-13 02:23:31,170 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-5000/stderr.txt" ]; then echo "/results/orders/authn-users-5000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-5000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5000/stderr.txt file found" 2024-04-13 02:23:31,170 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 02:23:31,996 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 02:23:31,996 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:31,996 DEBUG --- stdout --- 2024-04-13 02:23:31,996 DEBUG /results/orders/authn-users-5000/stderr.txt file found 2024-04-13 02:23:31,996 DEBUG --- stderr --- 2024-04-13 02:23:31,996 DEBUG 2024-04-13 02:23:31,997 INFO 2024-04-13 02:23:31,997 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 02:23:31,997 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:32,323 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:32,323 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:32,323 DEBUG --- stdout --- 2024-04-13 02:23:32,323 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:32,323 DEBUG --- stderr --- 2024-04-13 02:23:32,323 DEBUG 2024-04-13 02:23:32,323 INFO 2024-04-13 02:23:32,324 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-5000/stderr.txt 2024-04-13 02:23:33,148 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 02:23:33,148 DEBUG --- stdout --- 2024-04-13 02:23:33,148 DEBUG 0 /results/orders/authn-users-5000/stderr.txt 2024-04-13 02:23:33,148 DEBUG --- stderr --- 2024-04-13 02:23:33,148 DEBUG 2024-04-13 02:23:33,148 INFO - Download file (size 0k) 2024-04-13 02:23:33,149 INFO 2024-04-13 02:23:33,149 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 02:23:33,149 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:33,466 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:33,466 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:33,466 DEBUG --- stdout --- 2024-04-13 02:23:33,466 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:33,466 DEBUG --- stderr --- 2024-04-13 02:23:33,466 DEBUG source : /results/orders/authn-users-5000/stderr.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/stderr.txt 2024-04-13 02:23:33,466 INFO 2024-04-13 02:23:33,466 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 02:23:33,466 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:33,767 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:33,768 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:33,768 DEBUG --- stdout --- 2024-04-13 02:23:33,768 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:33,768 DEBUG --- stderr --- 2024-04-13 02:23:33,768 DEBUG 2024-04-13 02:23:33,768 INFO 2024-04-13 02:23:33,768 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-5000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/stderr.txt 2024-04-13 02:23:33,768 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 02:23:34,542 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:34,543 DEBUG --- stdout --- 2024-04-13 02:23:34,543 DEBUG tar: Removing leading `/' from member names 2024-04-13 02:23:34,543 DEBUG --- stderr --- 2024-04-13 02:23:34,543 DEBUG 2024-04-13 02:23:34,544 INFO 2024-04-13 02:23:34,544 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 02:23:34,544 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:34,897 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:34,897 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:34,897 DEBUG --- stdout --- 2024-04-13 02:23:34,897 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:34,897 DEBUG --- stderr --- 2024-04-13 02:23:34,897 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 02:23:34,898 INFO 2024-04-13 02:23:34,898 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 02:23:34,898 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:35,212 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:35,212 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:35,212 DEBUG --- stdout --- 2024-04-13 02:23:35,213 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:35,213 DEBUG --- stderr --- 2024-04-13 02:23:35,213 DEBUG 2024-04-13 02:23:35,213 INFO 2024-04-13 02:23:35,213 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-5000/stdout.txt" ]; then echo "/results/orders/authn-users-5000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-5000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5000/stdout.txt file found" 2024-04-13 02:23:35,213 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 02:23:36,036 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 02:23:36,036 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:36,036 DEBUG --- stdout --- 2024-04-13 02:23:36,037 DEBUG /results/orders/authn-users-5000/stdout.txt file found 2024-04-13 02:23:36,037 DEBUG --- stderr --- 2024-04-13 02:23:36,037 DEBUG 2024-04-13 02:23:36,037 INFO 2024-04-13 02:23:36,037 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 02:23:36,037 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:36,362 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:36,362 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:36,362 DEBUG --- stdout --- 2024-04-13 02:23:36,363 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:36,363 DEBUG --- stderr --- 2024-04-13 02:23:36,363 DEBUG 2024-04-13 02:23:36,363 INFO 2024-04-13 02:23:36,363 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-5000/stdout.txt 2024-04-13 02:23:37,197 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 02:23:37,197 DEBUG --- stdout --- 2024-04-13 02:23:37,197 DEBUG 552 /results/orders/authn-users-5000/stdout.txt 2024-04-13 02:23:37,197 DEBUG --- stderr --- 2024-04-13 02:23:37,197 DEBUG 2024-04-13 02:23:37,197 INFO - Download file (size 552k) 2024-04-13 02:23:37,198 INFO 2024-04-13 02:23:37,198 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 02:23:37,198 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:37,500 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:37,500 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:37,500 DEBUG --- stdout --- 2024-04-13 02:23:37,500 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:37,500 DEBUG --- stderr --- 2024-04-13 02:23:37,500 DEBUG source : /results/orders/authn-users-5000/stdout.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/stdout.txt 2024-04-13 02:23:37,500 INFO 2024-04-13 02:23:37,500 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 02:23:37,501 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:37,796 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:37,796 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:37,796 DEBUG --- stdout --- 2024-04-13 02:23:37,796 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:37,796 DEBUG --- stderr --- 2024-04-13 02:23:37,796 DEBUG 2024-04-13 02:23:37,796 INFO 2024-04-13 02:23:37,796 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-5000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/stdout.txt 2024-04-13 02:23:37,797 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 02:23:38,857 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:38,858 DEBUG --- stdout --- 2024-04-13 02:23:38,858 DEBUG tar: Removing leading `/' from member names 2024-04-13 02:23:38,858 DEBUG --- stderr --- 2024-04-13 02:23:38,858 DEBUG ________________________________________________________________________________ [2024-04-13 02:23:38] authn-users-5000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-13 02:23:38,858 INFO 2024-04-13 02:23:38,858 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 02:23:38,859 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:39,158 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:39,158 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:39,158 DEBUG --- stdout --- 2024-04-13 02:23:39,158 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:39,158 DEBUG --- stderr --- 2024-04-13 02:23:39,158 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 02:23:39,158 INFO 2024-04-13 02:23:39,158 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 02:23:39,158 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:39,459 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:39,459 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:39,459 DEBUG --- stdout --- 2024-04-13 02:23:39,459 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:39,459 DEBUG --- stderr --- 2024-04-13 02:23:39,460 DEBUG 2024-04-13 02:23:39,460 INFO 2024-04-13 02:23:39,461 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-5000/cmd.txt" ]; then echo "/results/orders/authn-users-5000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-5000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5000/cmd.txt file found" 2024-04-13 02:23:39,461 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 02:23:40,293 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 02:23:40,295 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:40,295 DEBUG --- stdout --- 2024-04-13 02:23:40,295 DEBUG /results/orders/authn-users-5000/cmd.txt file found 2024-04-13 02:23:40,295 DEBUG --- stderr --- 2024-04-13 02:23:40,295 DEBUG 2024-04-13 02:23:40,295 INFO 2024-04-13 02:23:40,295 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 02:23:40,296 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:40,649 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:40,649 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:40,649 DEBUG --- stdout --- 2024-04-13 02:23:40,649 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:40,649 DEBUG --- stderr --- 2024-04-13 02:23:40,649 DEBUG 2024-04-13 02:23:40,649 INFO 2024-04-13 02:23:40,650 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-5000/cmd.txt 2024-04-13 02:23:41,479 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 02:23:41,479 DEBUG --- stdout --- 2024-04-13 02:23:41,479 DEBUG 4 /results/orders/authn-users-5000/cmd.txt 2024-04-13 02:23:41,479 DEBUG --- stderr --- 2024-04-13 02:23:41,479 DEBUG 2024-04-13 02:23:41,479 INFO - Download file (size 4k) 2024-04-13 02:23:41,479 INFO 2024-04-13 02:23:41,479 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 02:23:41,479 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:41,784 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:41,784 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:41,784 DEBUG --- stdout --- 2024-04-13 02:23:41,784 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:41,784 DEBUG --- stderr --- 2024-04-13 02:23:41,784 DEBUG source : /results/orders/authn-users-5000/cmd.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/cmd.txt 2024-04-13 02:23:41,784 INFO 2024-04-13 02:23:41,784 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 02:23:41,784 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 02:23:42,100 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 02:23:42,100 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:42,100 DEBUG --- stdout --- 2024-04-13 02:23:42,101 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 02:23:42,101 DEBUG --- stderr --- 2024-04-13 02:23:42,101 DEBUG 2024-04-13 02:23:42,101 INFO 2024-04-13 02:23:42,101 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-5000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/cmd.txt 2024-04-13 02:23:42,101 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 02:23:42,864 INFO [loop_until]: OK (rc = 0) 2024-04-13 02:23:42,865 DEBUG --- stdout --- 2024-04-13 02:23:42,865 DEBUG tar: Removing leading `/' from member names 2024-04-13 02:23:42,865 DEBUG --- stderr --- 2024-04-13 02:23:42,865 DEBUG 2024-04-13 02:23:42,865 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/cmd.txt is small (1 lines), printing all lines: 2024-04-13 02:23:42,865 DEBUG ----- output ----- 2024-04-13 02:23:42,865 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-5000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-13 02:23:42,865 INFO ________________________________________________________________________________ [2024-04-13 02:23:42] authn-users-5000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-13 02:23:42,866 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/rc.txt is small (1 lines), printing all lines: 2024-04-13 02:23:42,866 DEBUG ----- output ----- 2024-04-13 02:23:42,866 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-13 02:23:42,866 INFO ________________________________________________________________________________ [2024-04-13 02:23:42] authn-users-5000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-13 02:23:42,868 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/05_authn-users-5000/stdout.txt is 9477 lines): 2024-04-13 02:23:42,868 DEBUG ----- output ----- 2024-04-13 02:23:42,868 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-13 02:23:42,868 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-13 02:23:42,868 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-13 02:23:42,868 INFO 2024-04-13 02:23:42,868 INFO ================================================================================ 2024-04-13 02:23:42,868 INFO 2024-04-13 02:21:14 5s elapsed 2024-04-13 02:23:42,868 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,868 INFO > Global (OK=2085 KO=0 ) 2024-04-13 02:23:42,868 INFO > userRestLogin (OK=2085 KO=0 ) 2024-04-13 02:23:42,868 INFO 2024-04-13 02:23:42,868 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,868 INFO [- ] 0% 2024-04-13 02:23:42,868 INFO waiting: 18077173 / active: 11 / done: 2084 2024-04-13 02:23:42,868 INFO ================================================================================ 2024-04-13 02:23:42,868 INFO 2024-04-13 02:23:42,868 INFO 2024-04-13 02:23:42,868 INFO ================================================================================ 2024-04-13 02:23:42,868 INFO 2024-04-13 02:21:19 10s elapsed 2024-04-13 02:23:42,868 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,868 INFO > Global (OK=8336 KO=0 ) 2024-04-13 02:23:42,868 INFO > userRestLogin (OK=8336 KO=0 ) 2024-04-13 02:23:42,868 INFO 2024-04-13 02:23:42,868 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,868 INFO [- ] 0% 2024-04-13 02:23:42,868 INFO waiting: 18070912 / active: 21 / done: 8335 2024-04-13 02:23:42,868 INFO ================================================================================ 2024-04-13 02:23:42,868 INFO 2024-04-13 02:23:42,868 INFO 2024-04-13 02:23:42,868 INFO ================================================================================ 2024-04-13 02:23:42,868 INFO 2024-04-13 02:21:24 15s elapsed 2024-04-13 02:23:42,869 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,869 INFO > Global (OK=18748 KO=0 ) 2024-04-13 02:23:42,869 INFO > userRestLogin (OK=18748 KO=0 ) 2024-04-13 02:23:42,869 INFO 2024-04-13 02:23:42,869 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,869 INFO [- ] 0% 2024-04-13 02:23:42,869 INFO waiting: 18060487 / active: 33 / done: 18748 2024-04-13 02:23:42,869 INFO ================================================================================ 2024-04-13 02:23:42,869 INFO 2024-04-13 02:23:42,869 INFO 2024-04-13 02:23:42,869 INFO ================================================================================ 2024-04-13 02:23:42,869 INFO 2024-04-13 02:21:29 20s elapsed 2024-04-13 02:23:42,869 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,869 INFO > Global (OK=33331 KO=0 ) 2024-04-13 02:23:42,869 INFO > userRestLogin (OK=33331 KO=0 ) 2024-04-13 02:23:42,869 INFO 2024-04-13 02:23:42,869 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,869 INFO [- ] 0% 2024-04-13 02:23:42,869 INFO waiting: 18045890 / active: 47 / done: 33331 2024-04-13 02:23:42,869 INFO ================================================================================ 2024-04-13 02:23:42,869 INFO 2024-04-13 02:23:42,869 INFO 2024-04-13 02:23:42,869 INFO ================================================================================ 2024-04-13 02:23:42,869 INFO 2024-04-13 02:21:34 25s elapsed 2024-04-13 02:23:42,869 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,869 INFO > Global (OK=52069 KO=0 ) 2024-04-13 02:23:42,869 INFO > userRestLogin (OK=52069 KO=0 ) 2024-04-13 02:23:42,869 INFO 2024-04-13 02:23:42,869 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,869 INFO [- ] 0% 2024-04-13 02:23:42,869 INFO waiting: 18027134 / active: 65 / done: 52069 2024-04-13 02:23:42,869 INFO ================================================================================ 2024-04-13 02:23:42,869 INFO 2024-04-13 02:23:42,869 INFO 2024-04-13 02:23:42,869 INFO ================================================================================ 2024-04-13 02:23:42,869 INFO 2024-04-13 02:21:39 30s elapsed 2024-04-13 02:23:42,869 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,869 INFO > Global (OK=74855 KO=0 ) 2024-04-13 02:23:42,869 INFO > userRestLogin (OK=74855 KO=0 ) 2024-04-13 02:23:42,869 INFO 2024-04-13 02:23:42,869 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,869 INFO [- ] 0% 2024-04-13 02:23:42,870 INFO waiting: 18004331 / active: 82 / done: 74855 2024-04-13 02:23:42,870 INFO ================================================================================ 2024-04-13 02:23:42,870 INFO 2024-04-13 02:23:42,870 INFO 2024-04-13 02:23:42,870 INFO ================================================================================ 2024-04-13 02:23:42,870 INFO 2024-04-13 02:21:44 35s elapsed 2024-04-13 02:23:42,870 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,870 INFO > Global (OK=100038 KO=0 ) 2024-04-13 02:23:42,870 INFO > userRestLogin (OK=100038 KO=0 ) 2024-04-13 02:23:42,870 INFO 2024-04-13 02:23:42,870 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,870 INFO [- ] 0% 2024-04-13 02:23:42,870 INFO waiting: 17979020 / active: 211 / done: 100037 2024-04-13 02:23:42,870 INFO ================================================================================ 2024-04-13 02:23:42,870 INFO 2024-04-13 02:23:42,870 INFO 2024-04-13 02:23:42,870 INFO ================================================================================ 2024-04-13 02:23:42,870 INFO 2024-04-13 02:21:49 40s elapsed 2024-04-13 02:23:42,870 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,870 INFO > Global (OK=124830 KO=0 ) 2024-04-13 02:23:42,870 INFO > userRestLogin (OK=124830 KO=0 ) 2024-04-13 02:23:42,870 INFO 2024-04-13 02:23:42,870 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,870 INFO [- ] 0% 2024-04-13 02:23:42,870 INFO waiting: 17954346 / active: 92 / done: 124830 2024-04-13 02:23:42,870 INFO ================================================================================ 2024-04-13 02:23:42,870 INFO 2024-04-13 02:23:42,870 INFO 2024-04-13 02:23:42,870 INFO [...] 2024-04-13 02:23:42,873 INFO 2024-04-13 02:23:42,873 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,873 INFO [#########################################################################-] 99% 2024-04-13 02:23:42,873 INFO waiting: 100237 / active: 94 / done: 17978937 2024-04-13 02:23:42,873 INFO ================================================================================ 2024-04-13 02:23:42,873 INFO 2024-04-13 02:23:42,873 INFO 2024-04-13 02:23:42,873 INFO ================================================================================ 2024-04-13 02:23:42,873 INFO 2024-04-13 03:21:24 3615s elapsed 2024-04-13 02:23:42,873 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,873 INFO > Global (OK=18004047 KO=1 ) 2024-04-13 02:23:42,873 INFO > userRestLogin (OK=18004047 KO=1 ) 2024-04-13 02:23:42,873 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 02:23:42,873 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-13 02:23:42,873 INFO ), but actually found 502 2024-04-13 02:23:42,873 INFO 2024-04-13 02:23:42,873 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,873 INFO [#########################################################################-] 99% 2024-04-13 02:23:42,873 INFO waiting: 75142 / active: 78 / done: 18004048 2024-04-13 02:23:42,874 INFO ================================================================================ 2024-04-13 02:23:42,874 INFO 2024-04-13 02:23:42,874 INFO 2024-04-13 02:23:42,874 INFO ================================================================================ 2024-04-13 02:23:42,874 INFO 2024-04-13 03:21:29 3620s elapsed 2024-04-13 02:23:42,874 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,874 INFO > Global (OK=18028683 KO=1 ) 2024-04-13 02:23:42,874 INFO > userRestLogin (OK=18028683 KO=1 ) 2024-04-13 02:23:42,874 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 02:23:42,874 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-13 02:23:42,874 INFO ), but actually found 502 2024-04-13 02:23:42,874 INFO 2024-04-13 02:23:42,874 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,874 INFO [#########################################################################-] 99% 2024-04-13 02:23:42,874 INFO waiting: 50052 / active: 533 / done: 18028683 2024-04-13 02:23:42,874 INFO ================================================================================ 2024-04-13 02:23:42,874 INFO 2024-04-13 02:23:42,874 INFO 2024-04-13 02:23:42,874 INFO ================================================================================ 2024-04-13 02:23:42,874 INFO 2024-04-13 03:21:34 3625s elapsed 2024-04-13 02:23:42,874 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,874 INFO > Global (OK=18054382 KO=1 ) 2024-04-13 02:23:42,874 INFO > userRestLogin (OK=18054382 KO=1 ) 2024-04-13 02:23:42,874 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 02:23:42,874 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-13 02:23:42,874 INFO ), but actually found 502 2024-04-13 02:23:42,874 INFO 2024-04-13 02:23:42,874 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,874 INFO [#########################################################################-] 99% 2024-04-13 02:23:42,874 INFO waiting: 24792 / active: 93 / done: 18054383 2024-04-13 02:23:42,874 INFO ================================================================================ 2024-04-13 02:23:42,874 INFO 2024-04-13 02:23:42,874 INFO 2024-04-13 02:23:42,874 INFO ================================================================================ 2024-04-13 02:23:42,874 INFO 2024-04-13 03:21:39 3630s elapsed 2024-04-13 02:23:42,875 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,875 INFO > Global (OK=18079153 KO=1 ) 2024-04-13 02:23:42,875 INFO > userRestLogin (OK=18079153 KO=1 ) 2024-04-13 02:23:42,875 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 02:23:42,875 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-13 02:23:42,875 INFO ), but actually found 502 2024-04-13 02:23:42,875 INFO 2024-04-13 02:23:42,875 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,875 INFO [#########################################################################-] 99% 2024-04-13 02:23:42,875 INFO waiting: 0 / active: 116 / done: 18079152 2024-04-13 02:23:42,875 INFO ================================================================================ 2024-04-13 02:23:42,875 INFO 2024-04-13 02:23:42,875 INFO 2024-04-13 02:23:42,875 INFO ================================================================================ 2024-04-13 02:23:42,875 INFO 2024-04-13 03:21:39 3630s elapsed 2024-04-13 02:23:42,875 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 02:23:42,875 INFO > Global (OK=18079267 KO=1 ) 2024-04-13 02:23:42,875 INFO > userRestLogin (OK=18079267 KO=1 ) 2024-04-13 02:23:42,875 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 02:23:42,875 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-13 02:23:42,875 INFO ), but actually found 502 2024-04-13 02:23:42,875 INFO 2024-04-13 02:23:42,875 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 02:23:42,875 INFO [##########################################################################]100% 2024-04-13 02:23:42,875 INFO waiting: 0 / active: 0 / done: 18079268 2024-04-13 02:23:42,875 INFO ================================================================================ 2024-04-13 02:23:42,875 INFO 2024-04-13 02:23:42,875 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-13 02:23:42,875 INFO 2024-04-13 02:23:42,875 INFO ---------------------- 2024-04-13 02:23:42,875 INFO [13/Apr/2024 03:21:40] Create tar file authn-users-5000.tar.gz 2024-04-13 02:23:42,875 INFO ---------------------- 2024-04-13 02:23:42,875 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-13 02:23:42,875 INFO RESULT_FOLDER=authn-users-5000 2024-04-13 02:23:42,875 INFO SIMULATION_PATH=authn-users-5000/loadtest-20240413012109294 2024-04-13 02:23:42,875 INFO PWD=/results/gatling/authn-users-5000/loadtest-20240413012109294 2024-04-13 02:23:42,875 INFO [cmd] tar -czvf /results/gatling//authn-users-5000.tar.gz --exclude=simulation.log * 2024-04-13 02:23:42,875 INFO /results/gatling 2024-04-13 02:23:42,875 INFO 2024-04-13 02:23:42,875 INFO ---------------------- 2024-04-13 02:23:42,876 INFO [13/Apr/2024 03:21:40] Update /results/gatling//latest 2024-04-13 02:23:42,876 INFO ---------------------- 2024-04-13 02:23:42,876 INFO 2024-04-13 02:23:42,876 INFO ---------------------- 2024-04-13 02:23:42,876 INFO [13/Apr/2024 03:21:40] Update /results/gatling//latest.tar.gz 2024-04-13 02:23:42,876 INFO ---------------------- 2024-04-13 02:23:42,876 INFO ________________________________________________________________________________ [2024-04-13 02:23:42] authn-users-5000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-13 02:23:42,876 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5000/stderr.txt is small (0 lines), printing all lines: 2024-04-13 02:23:42,876 DEBUG ----- output ----- 2024-04-13 02:23:42,876 DEBUG 2024-04-13 02:23:42,876 INFO ________________________________________________________________________________ [2024-04-13 02:23:42] authn-users-5000 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 02:23:42] authn-users-5000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-13 02:23:42] authn-users-5000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped