--Task-- name: authn-users-4000 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-3000'] preceding_task: authn-users-3000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 4000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-11 04:58:28] authn-users-4000 pre : N/A ________________________________________________________________________________ [2024-04-11 04:58:28] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-11 04:58:28] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-4000/stdout.txt Pod log : /results/orders/authn-users-4000 2024-04-11 04:58:28,308 INFO 2024-04-11 04:58:28,308 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-11 04:58:28,308 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:28,625 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:28,625 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:28,625 DEBUG --- stdout --- 2024-04-11 04:58:28,626 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:28,626 DEBUG --- stderr --- 2024-04-11 04:58:28,626 DEBUG ________________________________________________________________________________ [2024-04-11 04:58:28] authn-users-4000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-11 04:58:28,626 INFO 2024-04-11 04:58:28,626 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-11 04:58:28,626 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:28,963 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:28,963 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:28,963 DEBUG --- stdout --- 2024-04-11 04:58:28,963 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:28,963 DEBUG --- stderr --- 2024-04-11 04:58:28,963 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-4000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-11 04:58:28] authn-users-4000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-11 04:58:28,964 INFO 2024-04-11 04:58:28,965 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-11 04:58:28,965 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:29,323 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:29,323 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:29,323 DEBUG --- stdout --- 2024-04-11 04:58:29,323 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:29,323 DEBUG --- stderr --- 2024-04-11 04:58:29,323 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/order.json { "name": "authn-users-4000", "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-4000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-4000", "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=4000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-11 04:58:29] authn-users-4000 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-11 04:58:29,325 INFO 2024-04-11 04:58:29,325 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-11 04:58:29,325 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-11 04:58:29,566 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 04:58:29,566 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:29,566 DEBUG --- stdout --- 2024-04-11 04:58:29,566 DEBUG WAITING 2024-04-11 04:58:29,566 DEBUG --- stderr --- 2024-04-11 04:58:29,566 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 31 0 --:--:-- --:--:-- --:--:-- 31 ________________________________________________________________________________ [2024-04-11 04:58:29] authn-users-4000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-11 04:58:29,567 INFO 2024-04-11 04:58:29,567 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-11 04:58:29,567 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:29,883 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:29,884 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:29,884 DEBUG --- stdout --- 2024-04-11 04:58:29,884 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:29,884 DEBUG --- stderr --- 2024-04-11 04:58:29,884 DEBUG 2024-04-11 04:58:29,884 INFO 2024-04-11 04:58:29,884 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-4000/order.json overseer-0-78845f4f8c-v2r2q:/results/orders/order.json 2024-04-11 04:58:29,884 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 04:58:31,290 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:31,290 DEBUG --- stdout --- 2024-04-11 04:58:31,290 DEBUG 2024-04-11 04:58:31,290 DEBUG --- stderr --- 2024-04-11 04:58:31,290 DEBUG Order has been successfully forwarded to overseer [2024-04-11 04:58:31] - INFO: Check if Gatling simulation started successfully 2024-04-11 04:58:31,291 INFO 2024-04-11 04:58:31,291 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-11 04:58:31,291 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:31,618 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:31,619 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:31,619 DEBUG --- stdout --- 2024-04-11 04:58:31,619 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:31,619 DEBUG --- stderr --- 2024-04-11 04:58:31,619 DEBUG 2024-04-11 04:58:31,619 INFO 2024-04-11 04:58:31,619 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q -- head -n 25 /results/orders/authn-users-4000/stdout.txt | grep "started..." 2024-04-11 04:58:31,619 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-11 04:58:32,415 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-11 04:58:38,197 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-11 04:58:44,001 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-11 04:58:44,001 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:44,001 DEBUG --- stdout --- 2024-04-11 04:58:44,001 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-11 05:58:43 5s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=1662 KO=0 ) > userRestLogin (OK=1662 KO=0 ) ---- am.AMAuthN ---------------------------------------------------------------- [- ] 0% waiting: 14460491 / active: 7 / done: 1662 ================================================================================ 2024-04-11 04:58:44,001 DEBUG --- stderr --- 2024-04-11 04:58:44,002 DEBUG ________________________________________________________________________________ [2024-04-11 04:58:43] authn-users-4000 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 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 07s, 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-11 06:00:54] authn-users-4000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-11 06:00:54,691 INFO 2024-04-11 06:00:54,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-11 06:00:54,692 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:00:55,099 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:00:55,099 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:00:55,099 DEBUG --- stdout --- 2024-04-11 06:00:55,099 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:00:55,100 DEBUG --- stderr --- 2024-04-11 06:00:55,100 DEBUG 2024-04-11 06:00:55,100 INFO 2024-04-11 06:00:55,100 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q -- rm -f /results/orders/order.json 2024-04-11 06:00:55,100 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 06:00:55,985 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:00:55,985 DEBUG --- stdout --- 2024-04-11 06:00:55,985 DEBUG 2024-04-11 06:00:55,985 DEBUG --- stderr --- 2024-04-11 06:00:55,985 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-11 06:00:55] authn-users-4000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-11 06:00:55] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/order.json 2024-04-11 06:00:55,986 INFO 2024-04-11 06:00:55,986 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-11 06:00:55,986 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:00:56,310 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:00:56,311 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:00:56,311 DEBUG --- stdout --- 2024-04-11 06:00:56,311 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:00:56,311 DEBUG --- stderr --- 2024-04-11 06:00:56,311 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 06:00:56,311 INFO 2024-04-11 06:00:56,311 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-11 06:00:56,311 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:00:56,632 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:00:56,632 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:00:56,632 DEBUG --- stdout --- 2024-04-11 06:00:56,632 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:00:56,632 DEBUG --- stderr --- 2024-04-11 06:00:56,632 DEBUG 2024-04-11 06:00:56,633 INFO 2024-04-11 06:00:56,633 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4000/rc.txt" ]; then echo "/results/orders/authn-users-4000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-4000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4000/rc.txt file found" 2024-04-11 06:00:56,633 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 06:00:57,472 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 06:00:57,472 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:00:57,472 DEBUG --- stdout --- 2024-04-11 06:00:57,472 DEBUG /results/orders/authn-users-4000/rc.txt file found 2024-04-11 06:00:57,472 DEBUG --- stderr --- 2024-04-11 06:00:57,472 DEBUG 2024-04-11 06:00:57,472 INFO 2024-04-11 06:00:57,472 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-11 06:00:57,472 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:00:57,778 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:00:57,778 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:00:57,778 DEBUG --- stdout --- 2024-04-11 06:00:57,778 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:00:57,778 DEBUG --- stderr --- 2024-04-11 06:00:57,778 DEBUG 2024-04-11 06:00:57,779 INFO 2024-04-11 06:00:57,779 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-4000/rc.txt 2024-04-11 06:00:58,621 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 06:00:58,621 DEBUG --- stdout --- 2024-04-11 06:00:58,621 DEBUG 4 /results/orders/authn-users-4000/rc.txt 2024-04-11 06:00:58,621 DEBUG --- stderr --- 2024-04-11 06:00:58,621 DEBUG 2024-04-11 06:00:58,621 INFO - Download file (size 4k) 2024-04-11 06:00:58,622 INFO 2024-04-11 06:00:58,622 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-11 06:00:58,622 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:00:58,954 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:00:58,954 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:00:58,954 DEBUG --- stdout --- 2024-04-11 06:00:58,954 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:00:58,954 DEBUG --- stderr --- 2024-04-11 06:00:58,954 DEBUG source : /results/orders/authn-users-4000/rc.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/rc.txt 2024-04-11 06:00:58,954 INFO 2024-04-11 06:00:58,954 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-11 06:00:58,955 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:00:59,288 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:00:59,289 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:00:59,289 DEBUG --- stdout --- 2024-04-11 06:00:59,289 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:00:59,289 DEBUG --- stderr --- 2024-04-11 06:00:59,289 DEBUG 2024-04-11 06:00:59,289 INFO 2024-04-11 06:00:59,290 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-4000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/rc.txt 2024-04-11 06:00:59,290 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 06:01:00,084 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:00,084 DEBUG --- stdout --- 2024-04-11 06:01:00,084 DEBUG tar: Removing leading `/' from member names 2024-04-11 06:01:00,084 DEBUG --- stderr --- 2024-04-11 06:01:00,084 DEBUG 2024-04-11 06:01:00,085 INFO 2024-04-11 06:01:00,085 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-11 06:01:00,085 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:00,433 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:00,434 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:00,434 DEBUG --- stdout --- 2024-04-11 06:01:00,434 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:00,434 DEBUG --- stderr --- 2024-04-11 06:01:00,434 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 06:01:00,435 INFO 2024-04-11 06:01:00,435 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-11 06:01:00,435 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:00,778 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:00,778 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:00,778 DEBUG --- stdout --- 2024-04-11 06:01:00,778 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:00,779 DEBUG --- stderr --- 2024-04-11 06:01:00,779 DEBUG 2024-04-11 06:01:00,780 INFO 2024-04-11 06:01:00,780 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4000/stderr.txt" ]; then echo "/results/orders/authn-users-4000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-4000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4000/stderr.txt file found" 2024-04-11 06:01:00,780 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 06:01:01,661 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 06:01:01,662 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:01,662 DEBUG --- stdout --- 2024-04-11 06:01:01,662 DEBUG /results/orders/authn-users-4000/stderr.txt file found 2024-04-11 06:01:01,662 DEBUG --- stderr --- 2024-04-11 06:01:01,662 DEBUG 2024-04-11 06:01:01,662 INFO 2024-04-11 06:01:01,662 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-11 06:01:01,662 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:01,972 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:01,972 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:01,972 DEBUG --- stdout --- 2024-04-11 06:01:01,972 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:01,972 DEBUG --- stderr --- 2024-04-11 06:01:01,972 DEBUG 2024-04-11 06:01:01,972 INFO 2024-04-11 06:01:01,973 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-4000/stderr.txt 2024-04-11 06:01:02,803 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 06:01:02,803 DEBUG --- stdout --- 2024-04-11 06:01:02,803 DEBUG 0 /results/orders/authn-users-4000/stderr.txt 2024-04-11 06:01:02,803 DEBUG --- stderr --- 2024-04-11 06:01:02,803 DEBUG 2024-04-11 06:01:02,803 INFO - Download file (size 0k) 2024-04-11 06:01:02,804 INFO 2024-04-11 06:01:02,804 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-11 06:01:02,804 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:03,122 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:03,122 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:03,123 DEBUG --- stdout --- 2024-04-11 06:01:03,123 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:03,123 DEBUG --- stderr --- 2024-04-11 06:01:03,123 DEBUG source : /results/orders/authn-users-4000/stderr.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/stderr.txt 2024-04-11 06:01:03,123 INFO 2024-04-11 06:01:03,123 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-11 06:01:03,123 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:03,445 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:03,446 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:03,446 DEBUG --- stdout --- 2024-04-11 06:01:03,446 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:03,446 DEBUG --- stderr --- 2024-04-11 06:01:03,446 DEBUG 2024-04-11 06:01:03,446 INFO 2024-04-11 06:01:03,446 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-4000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/stderr.txt 2024-04-11 06:01:03,446 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 06:01:04,233 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:04,233 DEBUG --- stdout --- 2024-04-11 06:01:04,233 DEBUG tar: Removing leading `/' from member names 2024-04-11 06:01:04,233 DEBUG --- stderr --- 2024-04-11 06:01:04,233 DEBUG 2024-04-11 06:01:04,233 INFO 2024-04-11 06:01:04,233 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-11 06:01:04,234 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:04,546 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:04,546 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:04,546 DEBUG --- stdout --- 2024-04-11 06:01:04,546 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:04,546 DEBUG --- stderr --- 2024-04-11 06:01:04,546 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 06:01:04,547 INFO 2024-04-11 06:01:04,547 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-11 06:01:04,547 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:04,870 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:04,871 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:04,871 DEBUG --- stdout --- 2024-04-11 06:01:04,871 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:04,871 DEBUG --- stderr --- 2024-04-11 06:01:04,871 DEBUG 2024-04-11 06:01:04,872 INFO 2024-04-11 06:01:04,872 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4000/stdout.txt" ]; then echo "/results/orders/authn-users-4000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-4000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4000/stdout.txt file found" 2024-04-11 06:01:04,872 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 06:01:05,761 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 06:01:05,762 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:05,762 DEBUG --- stdout --- 2024-04-11 06:01:05,762 DEBUG /results/orders/authn-users-4000/stdout.txt file found 2024-04-11 06:01:05,762 DEBUG --- stderr --- 2024-04-11 06:01:05,762 DEBUG 2024-04-11 06:01:05,762 INFO 2024-04-11 06:01:05,762 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-11 06:01:05,762 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:06,101 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:06,102 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:06,102 DEBUG --- stdout --- 2024-04-11 06:01:06,102 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:06,102 DEBUG --- stderr --- 2024-04-11 06:01:06,102 DEBUG 2024-04-11 06:01:06,103 INFO 2024-04-11 06:01:06,103 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-4000/stdout.txt 2024-04-11 06:01:06,972 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 06:01:06,972 DEBUG --- stdout --- 2024-04-11 06:01:06,972 DEBUG 608 /results/orders/authn-users-4000/stdout.txt 2024-04-11 06:01:06,972 DEBUG --- stderr --- 2024-04-11 06:01:06,972 DEBUG 2024-04-11 06:01:06,972 INFO - Download file (size 608k) 2024-04-11 06:01:06,973 INFO 2024-04-11 06:01:06,973 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-11 06:01:06,973 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:07,342 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:07,342 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:07,342 DEBUG --- stdout --- 2024-04-11 06:01:07,342 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:07,342 DEBUG --- stderr --- 2024-04-11 06:01:07,342 DEBUG source : /results/orders/authn-users-4000/stdout.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/stdout.txt 2024-04-11 06:01:07,342 INFO 2024-04-11 06:01:07,343 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-11 06:01:07,343 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:07,657 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:07,657 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:07,657 DEBUG --- stdout --- 2024-04-11 06:01:07,657 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:07,657 DEBUG --- stderr --- 2024-04-11 06:01:07,657 DEBUG 2024-04-11 06:01:07,657 INFO 2024-04-11 06:01:07,658 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-4000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/stdout.txt 2024-04-11 06:01:07,658 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 06:01:08,754 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:08,754 DEBUG --- stdout --- 2024-04-11 06:01:08,755 DEBUG tar: Removing leading `/' from member names 2024-04-11 06:01:08,755 DEBUG --- stderr --- 2024-04-11 06:01:08,755 DEBUG ________________________________________________________________________________ [2024-04-11 06:01:08] authn-users-4000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-11 06:01:08,755 INFO 2024-04-11 06:01:08,755 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-11 06:01:08,755 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:09,082 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:09,083 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:09,083 DEBUG --- stdout --- 2024-04-11 06:01:09,083 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:09,083 DEBUG --- stderr --- 2024-04-11 06:01:09,083 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 06:01:09,083 INFO 2024-04-11 06:01:09,083 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-11 06:01:09,083 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:09,409 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:09,409 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:09,409 DEBUG --- stdout --- 2024-04-11 06:01:09,409 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:09,409 DEBUG --- stderr --- 2024-04-11 06:01:09,409 DEBUG 2024-04-11 06:01:09,410 INFO 2024-04-11 06:01:09,410 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4000/cmd.txt" ]; then echo "/results/orders/authn-users-4000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-4000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4000/cmd.txt file found" 2024-04-11 06:01:09,410 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 06:01:10,270 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 06:01:10,270 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:10,270 DEBUG --- stdout --- 2024-04-11 06:01:10,270 DEBUG /results/orders/authn-users-4000/cmd.txt file found 2024-04-11 06:01:10,270 DEBUG --- stderr --- 2024-04-11 06:01:10,270 DEBUG 2024-04-11 06:01:10,271 INFO 2024-04-11 06:01:10,271 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-11 06:01:10,271 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:10,597 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:10,598 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:10,598 DEBUG --- stdout --- 2024-04-11 06:01:10,598 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:10,598 DEBUG --- stderr --- 2024-04-11 06:01:10,598 DEBUG 2024-04-11 06:01:10,598 INFO 2024-04-11 06:01:10,598 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-4000/cmd.txt 2024-04-11 06:01:11,437 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 06:01:11,438 DEBUG --- stdout --- 2024-04-11 06:01:11,438 DEBUG 4 /results/orders/authn-users-4000/cmd.txt 2024-04-11 06:01:11,438 DEBUG --- stderr --- 2024-04-11 06:01:11,438 DEBUG 2024-04-11 06:01:11,438 INFO - Download file (size 4k) 2024-04-11 06:01:11,439 INFO 2024-04-11 06:01:11,439 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-11 06:01:11,439 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:11,770 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:11,770 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:11,770 DEBUG --- stdout --- 2024-04-11 06:01:11,770 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:11,770 DEBUG --- stderr --- 2024-04-11 06:01:11,770 DEBUG source : /results/orders/authn-users-4000/cmd.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/cmd.txt 2024-04-11 06:01:11,770 INFO 2024-04-11 06:01:11,770 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-11 06:01:11,770 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:12,084 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:12,085 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:12,085 DEBUG --- stdout --- 2024-04-11 06:01:12,085 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:12,085 DEBUG --- stderr --- 2024-04-11 06:01:12,085 DEBUG 2024-04-11 06:01:12,085 INFO 2024-04-11 06:01:12,085 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-4000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/cmd.txt 2024-04-11 06:01:12,085 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 06:01:12,887 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:12,887 DEBUG --- stdout --- 2024-04-11 06:01:12,887 DEBUG tar: Removing leading `/' from member names 2024-04-11 06:01:12,887 DEBUG --- stderr --- 2024-04-11 06:01:12,887 DEBUG 2024-04-11 06:01:12,888 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/cmd.txt is small (1 lines), printing all lines: 2024-04-11 06:01:12,888 DEBUG ----- output ----- 2024-04-11 06:01:12,888 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-4000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-11 06:01:12,888 INFO ________________________________________________________________________________ [2024-04-11 06:01:12] authn-users-4000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-11 06:01:12,889 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/rc.txt is small (1 lines), printing all lines: 2024-04-11 06:01:12,889 DEBUG ----- output ----- 2024-04-11 06:01:12,889 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-11 06:01:12,889 INFO ________________________________________________________________________________ [2024-04-11 06:01:12] authn-users-4000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-11 06:01:12,892 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-4000/stdout.txt is 10370 lines): 2024-04-11 06:01:12,893 DEBUG ----- output ----- 2024-04-11 06:01:12,893 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-11 06:01:12,893 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-11 06:01:12,893 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-11 06:01:12,893 INFO 2024-04-11 06:01:12,893 INFO ================================================================================ 2024-04-11 06:01:12,893 INFO 2024-04-11 05:58:43 5s elapsed 2024-04-11 06:01:12,893 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,893 INFO > Global (OK=1662 KO=0 ) 2024-04-11 06:01:12,893 INFO > userRestLogin (OK=1662 KO=0 ) 2024-04-11 06:01:12,893 INFO 2024-04-11 06:01:12,893 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,893 INFO [- ] 0% 2024-04-11 06:01:12,893 INFO waiting: 14460491 / active: 7 / done: 1662 2024-04-11 06:01:12,893 INFO ================================================================================ 2024-04-11 06:01:12,893 INFO 2024-04-11 06:01:12,893 INFO 2024-04-11 06:01:12,893 INFO ================================================================================ 2024-04-11 06:01:12,893 INFO 2024-04-11 05:58:48 10s elapsed 2024-04-11 06:01:12,893 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,893 INFO > Global (OK=6664 KO=0 ) 2024-04-11 06:01:12,893 INFO > userRestLogin (OK=6664 KO=0 ) 2024-04-11 06:01:12,893 INFO 2024-04-11 06:01:12,893 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,893 INFO [- ] 0% 2024-04-11 06:01:12,893 INFO waiting: 14455484 / active: 12 / done: 6664 2024-04-11 06:01:12,893 INFO ================================================================================ 2024-04-11 06:01:12,893 INFO 2024-04-11 06:01:12,893 INFO 2024-04-11 06:01:12,894 INFO ================================================================================ 2024-04-11 06:01:12,894 INFO 2024-04-11 05:58:53 15s elapsed 2024-04-11 06:01:12,894 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,894 INFO > Global (OK=14997 KO=0 ) 2024-04-11 06:01:12,894 INFO > userRestLogin (OK=14997 KO=0 ) 2024-04-11 06:01:12,894 INFO 2024-04-11 06:01:12,894 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,894 INFO [- ] 0% 2024-04-11 06:01:12,894 INFO waiting: 14447143 / active: 20 / done: 14997 2024-04-11 06:01:12,894 INFO ================================================================================ 2024-04-11 06:01:12,894 INFO 2024-04-11 06:01:12,894 INFO 2024-04-11 06:01:12,894 INFO ================================================================================ 2024-04-11 06:01:12,894 INFO 2024-04-11 05:58:58 20s elapsed 2024-04-11 06:01:12,894 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,894 INFO > Global (OK=26647 KO=0 ) 2024-04-11 06:01:12,894 INFO > userRestLogin (OK=26647 KO=0 ) 2024-04-11 06:01:12,894 INFO 2024-04-11 06:01:12,894 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,894 INFO [- ] 0% 2024-04-11 06:01:12,894 INFO waiting: 14435472 / active: 41 / done: 26647 2024-04-11 06:01:12,894 INFO ================================================================================ 2024-04-11 06:01:12,894 INFO 2024-04-11 06:01:12,894 INFO 2024-04-11 06:01:12,894 INFO ================================================================================ 2024-04-11 06:01:12,895 INFO 2024-04-11 05:59:03 25s elapsed 2024-04-11 06:01:12,895 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,895 INFO > Global (OK=41641 KO=0 ) 2024-04-11 06:01:12,895 INFO > userRestLogin (OK=41641 KO=0 ) 2024-04-11 06:01:12,895 INFO 2024-04-11 06:01:12,895 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,895 INFO [- ] 0% 2024-04-11 06:01:12,895 INFO waiting: 14420470 / active: 51 / done: 41639 2024-04-11 06:01:12,895 INFO ================================================================================ 2024-04-11 06:01:12,895 INFO 2024-04-11 06:01:12,895 INFO 2024-04-11 06:01:12,895 INFO ================================================================================ 2024-04-11 06:01:12,895 INFO 2024-04-11 05:59:08 30s elapsed 2024-04-11 06:01:12,895 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,895 INFO > Global (OK=59839 KO=0 ) 2024-04-11 06:01:12,895 INFO > userRestLogin (OK=59839 KO=0 ) 2024-04-11 06:01:12,895 INFO 2024-04-11 06:01:12,895 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,895 INFO [- ] 0% 2024-04-11 06:01:12,895 INFO waiting: 14402226 / active: 95 / done: 59839 2024-04-11 06:01:12,895 INFO ================================================================================ 2024-04-11 06:01:12,895 INFO 2024-04-11 06:01:12,895 INFO 2024-04-11 06:01:12,895 INFO ================================================================================ 2024-04-11 06:01:12,895 INFO 2024-04-11 05:59:13 35s elapsed 2024-04-11 06:01:12,896 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,896 INFO > Global (OK=79588 KO=0 ) 2024-04-11 06:01:12,896 INFO > userRestLogin (OK=79588 KO=0 ) 2024-04-11 06:01:12,896 INFO 2024-04-11 06:01:12,896 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,896 INFO [- ] 0% 2024-04-11 06:01:12,896 INFO waiting: 14382092 / active: 480 / done: 79588 2024-04-11 06:01:12,896 INFO ================================================================================ 2024-04-11 06:01:12,896 INFO 2024-04-11 06:01:12,896 INFO 2024-04-11 06:01:12,896 INFO ================================================================================ 2024-04-11 06:01:12,896 INFO 2024-04-11 05:59:18 40s elapsed 2024-04-11 06:01:12,896 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,896 INFO > Global (OK=99435 KO=0 ) 2024-04-11 06:01:12,896 INFO > userRestLogin (OK=99435 KO=0 ) 2024-04-11 06:01:12,896 INFO 2024-04-11 06:01:12,896 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,896 INFO [- ] 0% 2024-04-11 06:01:12,896 INFO waiting: 14362205 / active: 520 / done: 99435 2024-04-11 06:01:12,896 INFO ================================================================================ 2024-04-11 06:01:12,896 INFO 2024-04-11 06:01:12,896 INFO 2024-04-11 06:01:12,896 INFO [...] 2024-04-11 06:01:12,902 INFO 2024-04-11 06:01:12,902 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,902 INFO [#########################################################################-] 99% 2024-04-11 06:01:12,902 INFO waiting: 80444 / active: 811 / done: 14380905 2024-04-11 06:01:12,902 INFO ================================================================================ 2024-04-11 06:01:12,902 INFO 2024-04-11 06:01:12,903 INFO 2024-04-11 06:01:12,903 INFO ================================================================================ 2024-04-11 06:01:12,903 INFO 2024-04-11 06:58:53 3615s elapsed 2024-04-11 06:01:12,903 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,903 INFO > Global (OK=14401277 KO=2 ) 2024-04-11 06:01:12,903 INFO > userRestLogin (OK=14401277 KO=2 ) 2024-04-11 06:01:12,903 INFO ---- Errors -------------------------------------------------------------------- 2024-04-11 06:01:12,903 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 2 (100.0%) 2024-04-11 06:01:12,903 INFO ), but actually found 502 2024-04-11 06:01:12,903 INFO 2024-04-11 06:01:12,903 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,903 INFO [#########################################################################-] 99% 2024-04-11 06:01:12,903 INFO waiting: 60098 / active: 784 / done: 14401278 2024-04-11 06:01:12,903 INFO ================================================================================ 2024-04-11 06:01:12,903 INFO 2024-04-11 06:01:12,903 INFO 2024-04-11 06:01:12,903 INFO ================================================================================ 2024-04-11 06:01:12,903 INFO 2024-04-11 06:58:58 3620s elapsed 2024-04-11 06:01:12,903 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,903 INFO > Global (OK=14421767 KO=2 ) 2024-04-11 06:01:12,903 INFO > userRestLogin (OK=14421767 KO=2 ) 2024-04-11 06:01:12,903 INFO ---- Errors -------------------------------------------------------------------- 2024-04-11 06:01:12,903 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 2 (100.0%) 2024-04-11 06:01:12,903 INFO ), but actually found 502 2024-04-11 06:01:12,903 INFO 2024-04-11 06:01:12,904 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,904 INFO [#########################################################################-] 99% 2024-04-11 06:01:12,904 INFO waiting: 40152 / active: 240 / done: 14421768 2024-04-11 06:01:12,904 INFO ================================================================================ 2024-04-11 06:01:12,904 INFO 2024-04-11 06:01:12,904 INFO 2024-04-11 06:01:12,904 INFO ================================================================================ 2024-04-11 06:01:12,904 INFO 2024-04-11 06:59:03 3625s elapsed 2024-04-11 06:01:12,904 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,904 INFO > Global (OK=14441997 KO=2 ) 2024-04-11 06:01:12,904 INFO > userRestLogin (OK=14441997 KO=2 ) 2024-04-11 06:01:12,904 INFO ---- Errors -------------------------------------------------------------------- 2024-04-11 06:01:12,904 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 2 (100.0%) 2024-04-11 06:01:12,904 INFO ), but actually found 502 2024-04-11 06:01:12,904 INFO 2024-04-11 06:01:12,904 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,904 INFO [#########################################################################-] 99% 2024-04-11 06:01:12,904 INFO waiting: 20084 / active: 77 / done: 14441999 2024-04-11 06:01:12,904 INFO ================================================================================ 2024-04-11 06:01:12,904 INFO 2024-04-11 06:01:12,904 INFO 2024-04-11 06:01:12,904 INFO ================================================================================ 2024-04-11 06:01:12,904 INFO 2024-04-11 06:59:08 3630s elapsed 2024-04-11 06:01:12,904 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,904 INFO > Global (OK=14462087 KO=2 ) 2024-04-11 06:01:12,904 INFO > userRestLogin (OK=14462087 KO=2 ) 2024-04-11 06:01:12,904 INFO ---- Errors -------------------------------------------------------------------- 2024-04-11 06:01:12,905 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 2 (100.0%) 2024-04-11 06:01:12,905 INFO ), but actually found 502 2024-04-11 06:01:12,905 INFO 2024-04-11 06:01:12,905 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,905 INFO [#########################################################################-] 99% 2024-04-11 06:01:12,905 INFO waiting: 0 / active: 71 / done: 14462089 2024-04-11 06:01:12,905 INFO ================================================================================ 2024-04-11 06:01:12,905 INFO 2024-04-11 06:01:12,905 INFO 2024-04-11 06:01:12,905 INFO ================================================================================ 2024-04-11 06:01:12,905 INFO 2024-04-11 06:59:08 3630s elapsed 2024-04-11 06:01:12,905 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:01:12,905 INFO > Global (OK=14462158 KO=2 ) 2024-04-11 06:01:12,905 INFO > userRestLogin (OK=14462158 KO=2 ) 2024-04-11 06:01:12,905 INFO ---- Errors -------------------------------------------------------------------- 2024-04-11 06:01:12,905 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 2 (100.0%) 2024-04-11 06:01:12,905 INFO ), but actually found 502 2024-04-11 06:01:12,905 INFO 2024-04-11 06:01:12,905 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:01:12,905 INFO [##########################################################################]100% 2024-04-11 06:01:12,905 INFO waiting: 0 / active: 0 / done: 14462160 2024-04-11 06:01:12,905 INFO ================================================================================ 2024-04-11 06:01:12,905 INFO 2024-04-11 06:01:12,905 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-11 06:01:12,906 INFO 2024-04-11 06:01:12,906 INFO ---------------------- 2024-04-11 06:01:12,906 INFO [11/Apr/2024 06:59:08] Create tar file authn-users-4000.tar.gz 2024-04-11 06:01:12,906 INFO ---------------------- 2024-04-11 06:01:12,906 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-11 06:01:12,906 INFO RESULT_FOLDER=authn-users-4000 2024-04-11 06:01:12,906 INFO SIMULATION_PATH=authn-users-4000/loadtest-20240411045837860 2024-04-11 06:01:12,906 INFO PWD=/results/gatling/authn-users-4000/loadtest-20240411045837860 2024-04-11 06:01:12,906 INFO [cmd] tar -czvf /results/gatling//authn-users-4000.tar.gz --exclude=simulation.log * 2024-04-11 06:01:12,906 INFO /results/gatling 2024-04-11 06:01:12,906 INFO 2024-04-11 06:01:12,906 INFO ---------------------- 2024-04-11 06:01:12,906 INFO [11/Apr/2024 06:59:08] Update /results/gatling//latest 2024-04-11 06:01:12,906 INFO ---------------------- 2024-04-11 06:01:12,906 INFO 2024-04-11 06:01:12,906 INFO ---------------------- 2024-04-11 06:01:12,906 INFO [11/Apr/2024 06:59:08] Update /results/gatling//latest.tar.gz 2024-04-11 06:01:12,906 INFO ---------------------- 2024-04-11 06:01:12,906 INFO ________________________________________________________________________________ [2024-04-11 06:01:12] authn-users-4000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-11 06:01:12,907 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-4000/stderr.txt is small (0 lines), printing all lines: 2024-04-11 06:01:12,907 DEBUG ----- output ----- 2024-04-11 06:01:12,907 DEBUG 2024-04-11 06:01:12,907 INFO ________________________________________________________________________________ [2024-04-11 06:01:12] authn-users-4000 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-11 06:01:12] authn-users-4000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-11 06:01:12] authn-users-4000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped