--Task-- name: authn-users-3000 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-1000'] preceding_task: authn-users-1000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 3000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-14 19:41:23] authn-users-3000 pre : N/A ________________________________________________________________________________ [2024-04-14 19:41:23] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-14 19:41:23] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-3000/stdout.txt Pod log : /results/orders/authn-users-3000 2024-04-14 19:41:23,875 INFO 2024-04-14 19:41:23,875 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-14 19:41:23,875 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:24,212 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:24,213 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:24,213 DEBUG --- stdout --- 2024-04-14 19:41:24,213 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:24,213 DEBUG --- stderr --- 2024-04-14 19:41:24,213 DEBUG ________________________________________________________________________________ [2024-04-14 19:41:24] authn-users-3000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-14 19:41:24,213 INFO 2024-04-14 19:41:24,213 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-14 19:41:24,213 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:24,555 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:24,555 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:24,555 DEBUG --- stdout --- 2024-04-14 19:41:24,555 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:24,555 DEBUG --- stderr --- 2024-04-14 19:41:24,555 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-3000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-14 19:41:24] authn-users-3000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-14 19:41:24,556 INFO 2024-04-14 19:41:24,556 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-14 19:41:24,556 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:24,892 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:24,892 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:24,892 DEBUG --- stdout --- 2024-04-14 19:41:24,892 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:24,892 DEBUG --- stderr --- 2024-04-14 19:41:24,892 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/order.json { "name": "authn-users-3000", "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-3000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-3000", "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=3000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-14 19:41:24] authn-users-3000 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-14 19:41:24,894 INFO 2024-04-14 19:41:24,894 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-14 19:41:24,894 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 19:41:25,125 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 19:41:25,125 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:25,125 DEBUG --- stdout --- 2024-04-14 19:41:25,125 DEBUG WAITING 2024-04-14 19:41:25,126 DEBUG --- stderr --- 2024-04-14 19:41:25,126 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-14 19:41:25] authn-users-3000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-14 19:41:25,126 INFO 2024-04-14 19:41:25,126 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-14 19:41:25,126 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:25,428 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:25,428 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:25,428 DEBUG --- stdout --- 2024-04-14 19:41:25,428 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:25,428 DEBUG --- stderr --- 2024-04-14 19:41:25,428 DEBUG 2024-04-14 19:41:25,428 INFO 2024-04-14 19:41:25,428 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/03_authn-users-3000/order.json overseer-0-5584f6c4db-bcn5q:/results/orders/order.json 2024-04-14 19:41:25,428 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 19:41:26,836 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:26,836 DEBUG --- stdout --- 2024-04-14 19:41:26,836 DEBUG 2024-04-14 19:41:26,836 DEBUG --- stderr --- 2024-04-14 19:41:26,836 DEBUG Order has been successfully forwarded to overseer [2024-04-14 19:41:26] - INFO: Check if Gatling simulation started successfully 2024-04-14 19:41:26,837 INFO 2024-04-14 19:41:26,837 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-14 19:41:26,837 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:27,162 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:27,162 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:27,162 DEBUG --- stdout --- 2024-04-14 19:41:27,162 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:27,162 DEBUG --- stderr --- 2024-04-14 19:41:27,163 DEBUG 2024-04-14 19:41:27,163 INFO 2024-04-14 19:41:27,163 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- head -n 25 /results/orders/authn-users-3000/stdout.txt | grep "started..." 2024-04-14 19:41:27,163 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 19:41:27,954 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-14 19:41:33,733 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-04-14 19:41:33,733 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:33,733 DEBUG --- stdout --- 2024-04-14 19:41:33,734 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-14 19:41:33,734 DEBUG --- stderr --- 2024-04-14 19:41:33,734 DEBUG ________________________________________________________________________________ [2024-04-14 19:41:33] authn-users-3000 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 02s, 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-14 20:43:44] authn-users-3000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-14 20:43:44,274 INFO 2024-04-14 20:43:44,274 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-14 20:43:44,274 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:44,615 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:44,616 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:44,616 DEBUG --- stdout --- 2024-04-14 20:43:44,616 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:44,616 DEBUG --- stderr --- 2024-04-14 20:43:44,616 DEBUG 2024-04-14 20:43:44,616 INFO 2024-04-14 20:43:44,616 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- rm -f /results/orders/order.json 2024-04-14 20:43:44,616 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 20:43:45,439 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:45,440 DEBUG --- stdout --- 2024-04-14 20:43:45,440 DEBUG 2024-04-14 20:43:45,440 DEBUG --- stderr --- 2024-04-14 20:43:45,440 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-14 20:43:45] authn-users-3000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-14 20:43:45] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/order.json 2024-04-14 20:43:45,440 INFO 2024-04-14 20:43:45,441 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-14 20:43:45,441 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:45,744 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:45,745 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:45,745 DEBUG --- stdout --- 2024-04-14 20:43:45,745 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:45,745 DEBUG --- stderr --- 2024-04-14 20:43:45,745 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 20:43:45,745 INFO 2024-04-14 20:43:45,745 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 20:43:45,746 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:46,082 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:46,083 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:46,083 DEBUG --- stdout --- 2024-04-14 20:43:46,083 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:46,083 DEBUG --- stderr --- 2024-04-14 20:43:46,083 DEBUG 2024-04-14 20:43:46,084 INFO 2024-04-14 20:43:46,084 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3000/rc.txt" ]; then echo "/results/orders/authn-users-3000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/rc.txt file found" 2024-04-14 20:43:46,084 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 20:43:46,963 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 20:43:46,963 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:46,963 DEBUG --- stdout --- 2024-04-14 20:43:46,963 DEBUG /results/orders/authn-users-3000/rc.txt file found 2024-04-14 20:43:46,963 DEBUG --- stderr --- 2024-04-14 20:43:46,963 DEBUG 2024-04-14 20:43:46,964 INFO 2024-04-14 20:43:46,964 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-14 20:43:46,964 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:47,273 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:47,273 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:47,274 DEBUG --- stdout --- 2024-04-14 20:43:47,274 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:47,274 DEBUG --- stderr --- 2024-04-14 20:43:47,274 DEBUG 2024-04-14 20:43:47,274 INFO 2024-04-14 20:43:47,274 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-3000/rc.txt 2024-04-14 20:43:48,114 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 20:43:48,117 DEBUG --- stdout --- 2024-04-14 20:43:48,117 DEBUG 4 /results/orders/authn-users-3000/rc.txt 2024-04-14 20:43:48,117 DEBUG --- stderr --- 2024-04-14 20:43:48,117 DEBUG 2024-04-14 20:43:48,117 INFO - Download file (size 4k) 2024-04-14 20:43:48,117 INFO 2024-04-14 20:43:48,117 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-14 20:43:48,117 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:48,428 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:48,428 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:48,428 DEBUG --- stdout --- 2024-04-14 20:43:48,428 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:48,428 DEBUG --- stderr --- 2024-04-14 20:43:48,428 DEBUG source : /results/orders/authn-users-3000/rc.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/rc.txt 2024-04-14 20:43:48,428 INFO 2024-04-14 20:43:48,428 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-14 20:43:48,428 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:48,735 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:48,736 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:48,736 DEBUG --- stdout --- 2024-04-14 20:43:48,736 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:48,736 DEBUG --- stderr --- 2024-04-14 20:43:48,736 DEBUG 2024-04-14 20:43:48,736 INFO 2024-04-14 20:43:48,736 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-3000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/rc.txt 2024-04-14 20:43:48,736 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 20:43:49,481 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:49,481 DEBUG --- stdout --- 2024-04-14 20:43:49,481 DEBUG tar: Removing leading `/' from member names 2024-04-14 20:43:49,481 DEBUG --- stderr --- 2024-04-14 20:43:49,481 DEBUG 2024-04-14 20:43:49,482 INFO 2024-04-14 20:43:49,482 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-14 20:43:49,482 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:49,782 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:49,783 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:49,783 DEBUG --- stdout --- 2024-04-14 20:43:49,783 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:49,783 DEBUG --- stderr --- 2024-04-14 20:43:49,783 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 20:43:49,783 INFO 2024-04-14 20:43:49,783 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-14 20:43:49,783 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:50,099 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:50,099 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:50,099 DEBUG --- stdout --- 2024-04-14 20:43:50,099 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:50,099 DEBUG --- stderr --- 2024-04-14 20:43:50,099 DEBUG 2024-04-14 20:43:50,100 INFO 2024-04-14 20:43:50,100 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3000/stderr.txt" ]; then echo "/results/orders/authn-users-3000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/stderr.txt file found" 2024-04-14 20:43:50,100 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 20:43:50,909 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 20:43:50,910 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:50,910 DEBUG --- stdout --- 2024-04-14 20:43:50,910 DEBUG /results/orders/authn-users-3000/stderr.txt file found 2024-04-14 20:43:50,910 DEBUG --- stderr --- 2024-04-14 20:43:50,910 DEBUG 2024-04-14 20:43:50,910 INFO 2024-04-14 20:43:50,910 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-14 20:43:50,910 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:51,218 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:51,218 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:51,218 DEBUG --- stdout --- 2024-04-14 20:43:51,219 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:51,219 DEBUG --- stderr --- 2024-04-14 20:43:51,219 DEBUG 2024-04-14 20:43:51,219 INFO 2024-04-14 20:43:51,219 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-3000/stderr.txt 2024-04-14 20:43:52,045 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 20:43:52,046 DEBUG --- stdout --- 2024-04-14 20:43:52,046 DEBUG 0 /results/orders/authn-users-3000/stderr.txt 2024-04-14 20:43:52,046 DEBUG --- stderr --- 2024-04-14 20:43:52,046 DEBUG 2024-04-14 20:43:52,046 INFO - Download file (size 0k) 2024-04-14 20:43:52,046 INFO 2024-04-14 20:43:52,046 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-14 20:43:52,046 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:52,350 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:52,351 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:52,351 DEBUG --- stdout --- 2024-04-14 20:43:52,351 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:52,351 DEBUG --- stderr --- 2024-04-14 20:43:52,351 DEBUG source : /results/orders/authn-users-3000/stderr.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/stderr.txt 2024-04-14 20:43:52,352 INFO 2024-04-14 20:43:52,352 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-14 20:43:52,352 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:52,675 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:52,675 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:52,675 DEBUG --- stdout --- 2024-04-14 20:43:52,675 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:52,675 DEBUG --- stderr --- 2024-04-14 20:43:52,675 DEBUG 2024-04-14 20:43:52,676 INFO 2024-04-14 20:43:52,677 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-3000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/stderr.txt 2024-04-14 20:43:52,677 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 20:43:53,520 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:53,521 DEBUG --- stdout --- 2024-04-14 20:43:53,521 DEBUG tar: Removing leading `/' from member names 2024-04-14 20:43:53,521 DEBUG --- stderr --- 2024-04-14 20:43:53,521 DEBUG 2024-04-14 20:43:53,521 INFO 2024-04-14 20:43:53,521 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-14 20:43:53,521 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:53,850 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:53,851 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:53,851 DEBUG --- stdout --- 2024-04-14 20:43:53,851 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:53,851 DEBUG --- stderr --- 2024-04-14 20:43:53,851 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 20:43:53,851 INFO 2024-04-14 20:43:53,851 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-14 20:43:53,851 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:54,175 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:54,175 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:54,175 DEBUG --- stdout --- 2024-04-14 20:43:54,175 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:54,175 DEBUG --- stderr --- 2024-04-14 20:43:54,175 DEBUG 2024-04-14 20:43:54,176 INFO 2024-04-14 20:43:54,176 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3000/stdout.txt" ]; then echo "/results/orders/authn-users-3000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/stdout.txt file found" 2024-04-14 20:43:54,176 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 20:43:55,006 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 20:43:55,007 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:55,007 DEBUG --- stdout --- 2024-04-14 20:43:55,007 DEBUG /results/orders/authn-users-3000/stdout.txt file found 2024-04-14 20:43:55,007 DEBUG --- stderr --- 2024-04-14 20:43:55,007 DEBUG 2024-04-14 20:43:55,007 INFO 2024-04-14 20:43:55,007 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-14 20:43:55,007 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:55,311 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:55,311 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:55,311 DEBUG --- stdout --- 2024-04-14 20:43:55,311 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:55,311 DEBUG --- stderr --- 2024-04-14 20:43:55,311 DEBUG 2024-04-14 20:43:55,311 INFO 2024-04-14 20:43:55,312 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-3000/stdout.txt 2024-04-14 20:43:56,142 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 20:43:56,142 DEBUG --- stdout --- 2024-04-14 20:43:56,143 DEBUG 508 /results/orders/authn-users-3000/stdout.txt 2024-04-14 20:43:56,143 DEBUG --- stderr --- 2024-04-14 20:43:56,143 DEBUG 2024-04-14 20:43:56,143 INFO - Download file (size 508k) 2024-04-14 20:43:56,143 INFO 2024-04-14 20:43:56,143 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-14 20:43:56,143 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:56,463 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:56,463 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:56,463 DEBUG --- stdout --- 2024-04-14 20:43:56,463 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:56,463 DEBUG --- stderr --- 2024-04-14 20:43:56,463 DEBUG source : /results/orders/authn-users-3000/stdout.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/stdout.txt 2024-04-14 20:43:56,464 INFO 2024-04-14 20:43:56,464 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-14 20:43:56,464 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:56,768 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:56,768 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:56,768 DEBUG --- stdout --- 2024-04-14 20:43:56,768 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:56,768 DEBUG --- stderr --- 2024-04-14 20:43:56,768 DEBUG 2024-04-14 20:43:56,768 INFO 2024-04-14 20:43:56,768 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-3000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/stdout.txt 2024-04-14 20:43:56,768 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 20:43:57,847 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:57,847 DEBUG --- stdout --- 2024-04-14 20:43:57,847 DEBUG tar: Removing leading `/' from member names 2024-04-14 20:43:57,848 DEBUG --- stderr --- 2024-04-14 20:43:57,848 DEBUG ________________________________________________________________________________ [2024-04-14 20:43:57] authn-users-3000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-14 20:43:57,848 INFO 2024-04-14 20:43:57,848 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-14 20:43:57,848 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:58,164 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:58,165 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:58,165 DEBUG --- stdout --- 2024-04-14 20:43:58,165 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:58,165 DEBUG --- stderr --- 2024-04-14 20:43:58,165 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 20:43:58,165 INFO 2024-04-14 20:43:58,165 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-14 20:43:58,165 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:58,534 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:58,535 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:58,535 DEBUG --- stdout --- 2024-04-14 20:43:58,536 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:58,536 DEBUG --- stderr --- 2024-04-14 20:43:58,536 DEBUG 2024-04-14 20:43:58,536 INFO 2024-04-14 20:43:58,536 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3000/cmd.txt" ]; then echo "/results/orders/authn-users-3000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/cmd.txt file found" 2024-04-14 20:43:58,536 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 20:43:59,407 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 20:43:59,407 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:59,407 DEBUG --- stdout --- 2024-04-14 20:43:59,407 DEBUG /results/orders/authn-users-3000/cmd.txt file found 2024-04-14 20:43:59,407 DEBUG --- stderr --- 2024-04-14 20:43:59,407 DEBUG 2024-04-14 20:43:59,407 INFO 2024-04-14 20:43:59,408 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-14 20:43:59,408 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:43:59,727 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:43:59,727 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:43:59,727 DEBUG --- stdout --- 2024-04-14 20:43:59,727 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:43:59,727 DEBUG --- stderr --- 2024-04-14 20:43:59,727 DEBUG 2024-04-14 20:43:59,727 INFO 2024-04-14 20:43:59,728 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-3000/cmd.txt 2024-04-14 20:44:00,573 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 20:44:00,573 DEBUG --- stdout --- 2024-04-14 20:44:00,573 DEBUG 4 /results/orders/authn-users-3000/cmd.txt 2024-04-14 20:44:00,573 DEBUG --- stderr --- 2024-04-14 20:44:00,573 DEBUG 2024-04-14 20:44:00,573 INFO - Download file (size 4k) 2024-04-14 20:44:00,573 INFO 2024-04-14 20:44:00,573 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-14 20:44:00,573 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:44:00,874 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:44:00,875 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:44:00,875 DEBUG --- stdout --- 2024-04-14 20:44:00,875 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:44:00,875 DEBUG --- stderr --- 2024-04-14 20:44:00,875 DEBUG source : /results/orders/authn-users-3000/cmd.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/cmd.txt 2024-04-14 20:44:00,875 INFO 2024-04-14 20:44:00,875 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-14 20:44:00,875 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:44:01,178 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:44:01,178 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:44:01,178 DEBUG --- stdout --- 2024-04-14 20:44:01,178 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:44:01,178 DEBUG --- stderr --- 2024-04-14 20:44:01,178 DEBUG 2024-04-14 20:44:01,179 INFO 2024-04-14 20:44:01,179 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-3000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/cmd.txt 2024-04-14 20:44:01,179 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 20:44:01,926 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:44:01,926 DEBUG --- stdout --- 2024-04-14 20:44:01,926 DEBUG tar: Removing leading `/' from member names 2024-04-14 20:44:01,926 DEBUG --- stderr --- 2024-04-14 20:44:01,926 DEBUG 2024-04-14 20:44:01,927 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/cmd.txt is small (1 lines), printing all lines: 2024-04-14 20:44:01,927 DEBUG ----- output ----- 2024-04-14 20:44:01,927 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-3000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-14 20:44:01,927 INFO ________________________________________________________________________________ [2024-04-14 20:44:01] authn-users-3000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-14 20:44:01,927 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/rc.txt is small (1 lines), printing all lines: 2024-04-14 20:44:01,927 DEBUG ----- output ----- 2024-04-14 20:44:01,927 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-14 20:44:01,927 INFO ________________________________________________________________________________ [2024-04-14 20:44:01] authn-users-3000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-14 20:44:01,929 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/03_authn-users-3000/stdout.txt is 8746 lines): 2024-04-14 20:44:01,929 DEBUG ----- output ----- 2024-04-14 20:44:01,929 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-14 20:44:01,929 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-14 20:44:01,929 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-14 20:44:01,929 INFO 2024-04-14 20:44:01,929 INFO ================================================================================ 2024-04-14 20:44:01,929 INFO 2024-04-14 20:41:38 5s elapsed 2024-04-14 20:44:01,929 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,929 INFO > Global (OK=1253 KO=0 ) 2024-04-14 20:44:01,929 INFO > userRestLogin (OK=1253 KO=0 ) 2024-04-14 20:44:01,929 INFO 2024-04-14 20:44:01,929 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,929 INFO [- ] 0% 2024-04-14 20:44:01,929 INFO waiting: 10852695 / active: 5 / done: 1253 2024-04-14 20:44:01,929 INFO ================================================================================ 2024-04-14 20:44:01,929 INFO 2024-04-14 20:44:01,929 INFO 2024-04-14 20:44:01,929 INFO ================================================================================ 2024-04-14 20:44:01,929 INFO 2024-04-14 20:41:43 10s elapsed 2024-04-14 20:44:01,929 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,929 INFO > Global (OK=5004 KO=0 ) 2024-04-14 20:44:01,929 INFO > userRestLogin (OK=5004 KO=0 ) 2024-04-14 20:44:01,929 INFO 2024-04-14 20:44:01,929 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,929 INFO [- ] 0% 2024-04-14 20:44:01,929 INFO waiting: 10848940 / active: 9 / done: 5004 2024-04-14 20:44:01,929 INFO ================================================================================ 2024-04-14 20:44:01,929 INFO 2024-04-14 20:44:01,929 INFO 2024-04-14 20:44:01,930 INFO ================================================================================ 2024-04-14 20:44:01,930 INFO 2024-04-14 20:41:48 15s elapsed 2024-04-14 20:44:01,930 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,930 INFO > Global (OK=11256 KO=0 ) 2024-04-14 20:44:01,930 INFO > userRestLogin (OK=11256 KO=0 ) 2024-04-14 20:44:01,930 INFO 2024-04-14 20:44:01,930 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,930 INFO [- ] 0% 2024-04-14 20:44:01,930 INFO waiting: 10842681 / active: 16 / done: 11256 2024-04-14 20:44:01,930 INFO ================================================================================ 2024-04-14 20:44:01,930 INFO 2024-04-14 20:44:01,930 INFO 2024-04-14 20:44:01,930 INFO ================================================================================ 2024-04-14 20:44:01,930 INFO 2024-04-14 20:41:53 20s elapsed 2024-04-14 20:44:01,930 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,930 INFO > Global (OK=20007 KO=0 ) 2024-04-14 20:44:01,930 INFO > userRestLogin (OK=20007 KO=0 ) 2024-04-14 20:44:01,930 INFO 2024-04-14 20:44:01,930 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,930 INFO [- ] 0% 2024-04-14 20:44:01,930 INFO waiting: 10833926 / active: 20 / done: 20007 2024-04-14 20:44:01,930 INFO ================================================================================ 2024-04-14 20:44:01,930 INFO 2024-04-14 20:44:01,930 INFO 2024-04-14 20:44:01,930 INFO ================================================================================ 2024-04-14 20:44:01,930 INFO 2024-04-14 20:41:58 25s elapsed 2024-04-14 20:44:01,930 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,930 INFO > Global (OK=31253 KO=0 ) 2024-04-14 20:44:01,930 INFO > userRestLogin (OK=31253 KO=0 ) 2024-04-14 20:44:01,930 INFO 2024-04-14 20:44:01,930 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,930 INFO [- ] 0% 2024-04-14 20:44:01,930 INFO waiting: 10822672 / active: 28 / done: 31253 2024-04-14 20:44:01,930 INFO ================================================================================ 2024-04-14 20:44:01,930 INFO 2024-04-14 20:44:01,930 INFO 2024-04-14 20:44:01,930 INFO ================================================================================ 2024-04-14 20:44:01,930 INFO 2024-04-14 20:42:03 30s elapsed 2024-04-14 20:44:01,930 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,931 INFO > Global (OK=44926 KO=0 ) 2024-04-14 20:44:01,931 INFO > userRestLogin (OK=44926 KO=0 ) 2024-04-14 20:44:01,931 INFO 2024-04-14 20:44:01,931 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,931 INFO [- ] 0% 2024-04-14 20:44:01,931 INFO waiting: 10808990 / active: 37 / done: 44926 2024-04-14 20:44:01,931 INFO ================================================================================ 2024-04-14 20:44:01,931 INFO 2024-04-14 20:44:01,931 INFO 2024-04-14 20:44:01,931 INFO ================================================================================ 2024-04-14 20:44:01,931 INFO 2024-04-14 20:42:08 35s elapsed 2024-04-14 20:44:01,931 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,931 INFO > Global (OK=60145 KO=0 ) 2024-04-14 20:44:01,931 INFO > userRestLogin (OK=60145 KO=0 ) 2024-04-14 20:44:01,931 INFO 2024-04-14 20:44:01,931 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,931 INFO [- ] 0% 2024-04-14 20:44:01,931 INFO waiting: 10793765 / active: 43 / done: 60145 2024-04-14 20:44:01,931 INFO ================================================================================ 2024-04-14 20:44:01,931 INFO 2024-04-14 20:44:01,931 INFO 2024-04-14 20:44:01,931 INFO ================================================================================ 2024-04-14 20:44:01,931 INFO 2024-04-14 20:42:13 40s elapsed 2024-04-14 20:44:01,931 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,931 INFO > Global (OK=75089 KO=0 ) 2024-04-14 20:44:01,931 INFO > userRestLogin (OK=75089 KO=0 ) 2024-04-14 20:44:01,931 INFO 2024-04-14 20:44:01,931 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,931 INFO [- ] 0% 2024-04-14 20:44:01,931 INFO waiting: 10778825 / active: 41 / done: 75087 2024-04-14 20:44:01,931 INFO ================================================================================ 2024-04-14 20:44:01,931 INFO 2024-04-14 20:44:01,931 INFO 2024-04-14 20:44:01,931 INFO [...] 2024-04-14 20:44:01,934 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,934 INFO > Global (OK=10779193 KO=0 ) 2024-04-14 20:44:01,934 INFO > userRestLogin (OK=10779193 KO=0 ) 2024-04-14 20:44:01,934 INFO 2024-04-14 20:44:01,934 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,934 INFO [#########################################################################-] 99% 2024-04-14 20:44:01,934 INFO waiting: 74723 / active: 38 / done: 10779192 2024-04-14 20:44:01,934 INFO ================================================================================ 2024-04-14 20:44:01,934 INFO 2024-04-14 20:44:01,934 INFO 2024-04-14 20:44:01,934 INFO ================================================================================ 2024-04-14 20:44:01,934 INFO 2024-04-14 21:41:43 3610s elapsed 2024-04-14 20:44:01,934 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,934 INFO > Global (OK=10794082 KO=0 ) 2024-04-14 20:44:01,934 INFO > userRestLogin (OK=10794082 KO=0 ) 2024-04-14 20:44:01,934 INFO 2024-04-14 20:44:01,934 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,934 INFO [#########################################################################-] 99% 2024-04-14 20:44:01,934 INFO waiting: 59824 / active: 47 / done: 10794082 2024-04-14 20:44:01,934 INFO ================================================================================ 2024-04-14 20:44:01,934 INFO 2024-04-14 20:44:01,934 INFO 2024-04-14 20:44:01,934 INFO ================================================================================ 2024-04-14 20:44:01,934 INFO 2024-04-14 21:41:48 3615s elapsed 2024-04-14 20:44:01,934 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,934 INFO > Global (OK=10809100 KO=0 ) 2024-04-14 20:44:01,934 INFO > userRestLogin (OK=10809100 KO=0 ) 2024-04-14 20:44:01,934 INFO 2024-04-14 20:44:01,934 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,934 INFO [#########################################################################-] 99% 2024-04-14 20:44:01,934 INFO waiting: 44806 / active: 47 / done: 10809100 2024-04-14 20:44:01,934 INFO ================================================================================ 2024-04-14 20:44:01,934 INFO 2024-04-14 20:44:01,934 INFO 2024-04-14 20:44:01,934 INFO ================================================================================ 2024-04-14 20:44:01,934 INFO 2024-04-14 21:41:53 3620s elapsed 2024-04-14 20:44:01,935 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,935 INFO > Global (OK=10824099 KO=0 ) 2024-04-14 20:44:01,935 INFO > userRestLogin (OK=10824099 KO=0 ) 2024-04-14 20:44:01,935 INFO 2024-04-14 20:44:01,935 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,935 INFO [#########################################################################-] 99% 2024-04-14 20:44:01,935 INFO waiting: 29825 / active: 29 / done: 10824099 2024-04-14 20:44:01,935 INFO ================================================================================ 2024-04-14 20:44:01,935 INFO 2024-04-14 20:44:01,935 INFO 2024-04-14 20:44:01,935 INFO ================================================================================ 2024-04-14 20:44:01,935 INFO 2024-04-14 21:41:58 3625s elapsed 2024-04-14 20:44:01,935 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,935 INFO > Global (OK=10839046 KO=0 ) 2024-04-14 20:44:01,935 INFO > userRestLogin (OK=10839046 KO=0 ) 2024-04-14 20:44:01,935 INFO 2024-04-14 20:44:01,935 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,935 INFO [#########################################################################-] 99% 2024-04-14 20:44:01,935 INFO waiting: 14880 / active: 27 / done: 10839046 2024-04-14 20:44:01,935 INFO ================================================================================ 2024-04-14 20:44:01,935 INFO 2024-04-14 20:44:01,935 INFO 2024-04-14 20:44:01,935 INFO ================================================================================ 2024-04-14 20:44:01,935 INFO 2024-04-14 21:42:03 3630s elapsed 2024-04-14 20:44:01,935 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,935 INFO > Global (OK=10853952 KO=0 ) 2024-04-14 20:44:01,935 INFO > userRestLogin (OK=10853952 KO=0 ) 2024-04-14 20:44:01,935 INFO 2024-04-14 20:44:01,935 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,935 INFO [#########################################################################-] 99% 2024-04-14 20:44:01,935 INFO waiting: 0 / active: 1 / done: 10853952 2024-04-14 20:44:01,935 INFO ================================================================================ 2024-04-14 20:44:01,935 INFO 2024-04-14 20:44:01,935 INFO 2024-04-14 20:44:01,935 INFO ================================================================================ 2024-04-14 20:44:01,935 INFO 2024-04-14 21:42:03 3630s elapsed 2024-04-14 20:44:01,935 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 20:44:01,935 INFO > Global (OK=10853953 KO=0 ) 2024-04-14 20:44:01,935 INFO > userRestLogin (OK=10853953 KO=0 ) 2024-04-14 20:44:01,935 INFO 2024-04-14 20:44:01,935 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 20:44:01,935 INFO [##########################################################################]100% 2024-04-14 20:44:01,936 INFO waiting: 0 / active: 0 / done: 10853953 2024-04-14 20:44:01,936 INFO ================================================================================ 2024-04-14 20:44:01,936 INFO 2024-04-14 20:44:01,936 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-14 20:44:01,936 INFO 2024-04-14 20:44:01,936 INFO ---------------------- 2024-04-14 20:44:01,936 INFO [14/Apr/2024 21:42:03] Create tar file authn-users-3000.tar.gz 2024-04-14 20:44:01,936 INFO ---------------------- 2024-04-14 20:44:01,936 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-14 20:44:01,936 INFO RESULT_FOLDER=authn-users-3000 2024-04-14 20:44:01,936 INFO SIMULATION_PATH=authn-users-3000/loadtest-20240414194132844 2024-04-14 20:44:01,936 INFO PWD=/results/gatling/authn-users-3000/loadtest-20240414194132844 2024-04-14 20:44:01,936 INFO [cmd] tar -czvf /results/gatling//authn-users-3000.tar.gz --exclude=simulation.log * 2024-04-14 20:44:01,936 INFO /results/gatling 2024-04-14 20:44:01,936 INFO 2024-04-14 20:44:01,936 INFO ---------------------- 2024-04-14 20:44:01,936 INFO [14/Apr/2024 21:42:03] Update /results/gatling//latest 2024-04-14 20:44:01,936 INFO ---------------------- 2024-04-14 20:44:01,936 INFO 2024-04-14 20:44:01,936 INFO ---------------------- 2024-04-14 20:44:01,936 INFO [14/Apr/2024 21:42:03] Update /results/gatling//latest.tar.gz 2024-04-14 20:44:01,936 INFO ---------------------- 2024-04-14 20:44:01,936 INFO ________________________________________________________________________________ [2024-04-14 20:44:01] authn-users-3000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-14 20:44:01,936 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/stderr.txt is small (0 lines), printing all lines: 2024-04-14 20:44:01,936 DEBUG ----- output ----- 2024-04-14 20:44:01,936 DEBUG 2024-04-14 20:44:01,936 INFO ________________________________________________________________________________ [2024-04-14 20:44:01] authn-users-3000 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-14 20:44:01] authn-users-3000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-14 20:44:01] authn-users-3000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped