--Task-- name: authn-users-3500 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': 3500, 'rampup-duration': 30, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-10 03:42:36] authn-users-3500 pre : N/A ________________________________________________________________________________ [2024-04-10 03:42:36] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-10 03:42:36] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-3500/stdout.txt Pod log : /results/orders/authn-users-3500 2024-04-10 03:42:36,768 INFO 2024-04-10 03:42:36,768 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-10 03:42:36,768 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 03:42:37,080 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 03:42:37,080 INFO [loop_until]: OK (rc = 0) 2024-04-10 03:42:37,080 DEBUG --- stdout --- 2024-04-10 03:42:37,080 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 03:42:37,080 DEBUG --- stderr --- 2024-04-10 03:42:37,081 DEBUG ________________________________________________________________________________ [2024-04-10 03:42:37] authn-users-3500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-10 03:42:37,081 INFO 2024-04-10 03:42:37,081 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-10 03:42:37,081 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 03:42:37,390 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 03:42:37,390 INFO [loop_until]: OK (rc = 0) 2024-04-10 03:42:37,390 DEBUG --- stdout --- 2024-04-10 03:42:37,390 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 03:42:37,390 DEBUG --- stderr --- 2024-04-10 03:42:37,390 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-3500 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-10 03:42:37] authn-users-3500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-10 03:42:37,391 INFO 2024-04-10 03:42:37,391 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-10 03:42:37,391 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 03:42:37,701 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 03:42:37,701 INFO [loop_until]: OK (rc = 0) 2024-04-10 03:42:37,701 DEBUG --- stdout --- 2024-04-10 03:42:37,701 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 03:42:37,701 DEBUG --- stderr --- 2024-04-10 03:42:37,701 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/order.json { "name": "authn-users-3500", "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-3500 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-3500", "environment": { "JAVA_OPTS": "-Dam_host=xlou.iam.xlou-cdm.engineeringpit.com -DamadminPassword=**** -DamadminUsername=amadmin -Dduration=3600 -Dnum_users=1000000 -Drampup_duration=30 -DtestCase=am.AMAuthN -DtestMode=open -Dthroughput=3500 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-10 03:42:37] authn-users-3500 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-10 03:42:37,704 INFO 2024-04-10 03:42:37,704 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-10 03:42:37,704 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-10 03:42:37,944 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 03:42:37,944 INFO [loop_until]: OK (rc = 0) 2024-04-10 03:42:37,944 DEBUG --- stdout --- 2024-04-10 03:42:37,945 DEBUG WAITING 2024-04-10 03:42:37,945 DEBUG --- stderr --- 2024-04-10 03:42:37,945 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 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-10 03:42:37] authn-users-3500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-10 03:42:37,945 INFO 2024-04-10 03:42:37,945 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-10 03:42:37,945 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 03:42:38,256 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 03:42:38,256 INFO [loop_until]: OK (rc = 0) 2024-04-10 03:42:38,256 DEBUG --- stdout --- 2024-04-10 03:42:38,256 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 03:42:38,256 DEBUG --- stderr --- 2024-04-10 03:42:38,256 DEBUG 2024-04-10 03:42:38,257 INFO 2024-04-10 03:42:38,257 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/06_authn-users-3500/order.json overseer-0-6f8784665d-zq5hb:/results/orders/order.json 2024-04-10 03:42:38,257 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 03:42:39,646 INFO [loop_until]: OK (rc = 0) 2024-04-10 03:42:39,646 DEBUG --- stdout --- 2024-04-10 03:42:39,647 DEBUG 2024-04-10 03:42:39,647 DEBUG --- stderr --- 2024-04-10 03:42:39,647 DEBUG Order has been successfully forwarded to overseer [2024-04-10 03:42:39] - INFO: Check if Gatling simulation started successfully 2024-04-10 03:42:39,647 INFO 2024-04-10 03:42:39,647 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-10 03:42:39,647 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 03:42:39,987 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 03:42:39,987 INFO [loop_until]: OK (rc = 0) 2024-04-10 03:42:39,988 DEBUG --- stdout --- 2024-04-10 03:42:39,989 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 03:42:39,989 DEBUG --- stderr --- 2024-04-10 03:42:39,989 DEBUG 2024-04-10 03:42:39,989 INFO 2024-04-10 03:42:39,989 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb -- head -n 25 /results/orders/authn-users-3500/stdout.txt | grep "started..." 2024-04-10 03:42:39,989 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-10 03:42:40,761 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-10 03:42:46,536 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-10 03:42:52,299 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-10 03:42:52,300 INFO [loop_until]: OK (rc = 0) 2024-04-10 03:42:52,300 DEBUG --- stdout --- 2024-04-10 03:42:52,300 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-10 04:42:51 5s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=1461 KO=0 ) > userRestLogin (OK=1461 KO=0 ) ---- am.AMAuthN ---------------------------------------------------------------- [- ] 0% waiting: 12645281 / active: 7 / done: 1461 ================================================================================ 2024-04-10 03:42:52,300 DEBUG --- stderr --- 2024-04-10 03:42:52,301 DEBUG ________________________________________________________________________________ [2024-04-10 03:42:52] authn-users-3500 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-10 04:45:02] authn-users-3500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-10 04:45:02,790 INFO 2024-04-10 04:45:02,790 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-10 04:45:02,790 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:03,122 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:03,123 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:03,123 DEBUG --- stdout --- 2024-04-10 04:45:03,123 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:03,123 DEBUG --- stderr --- 2024-04-10 04:45:03,123 DEBUG 2024-04-10 04:45:03,123 INFO 2024-04-10 04:45:03,123 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb -- rm -f /results/orders/order.json 2024-04-10 04:45:03,123 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 04:45:03,936 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:03,936 DEBUG --- stdout --- 2024-04-10 04:45:03,936 DEBUG 2024-04-10 04:45:03,936 DEBUG --- stderr --- 2024-04-10 04:45:03,936 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-10 04:45:03] authn-users-3500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-10 04:45:03] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/order.json 2024-04-10 04:45:03,937 INFO 2024-04-10 04:45:03,937 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-10 04:45:03,937 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:04,249 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:04,249 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:04,250 DEBUG --- stdout --- 2024-04-10 04:45:04,250 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:04,250 DEBUG --- stderr --- 2024-04-10 04:45:04,250 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 04:45:04,250 INFO 2024-04-10 04:45:04,250 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-10 04:45:04,250 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:04,561 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:04,561 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:04,561 DEBUG --- stdout --- 2024-04-10 04:45:04,561 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:04,561 DEBUG --- stderr --- 2024-04-10 04:45:04,561 DEBUG 2024-04-10 04:45:04,562 INFO 2024-04-10 04:45:04,562 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3500/rc.txt" ]; then echo "/results/orders/authn-users-3500/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-3500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3500/rc.txt file found" 2024-04-10 04:45:04,562 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 04:45:05,429 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 04:45:05,429 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:05,429 DEBUG --- stdout --- 2024-04-10 04:45:05,429 DEBUG /results/orders/authn-users-3500/rc.txt file found 2024-04-10 04:45:05,429 DEBUG --- stderr --- 2024-04-10 04:45:05,429 DEBUG 2024-04-10 04:45:05,430 INFO 2024-04-10 04:45:05,430 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 04:45:05,430 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:05,751 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:05,752 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:05,752 DEBUG --- stdout --- 2024-04-10 04:45:05,752 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:05,752 DEBUG --- stderr --- 2024-04-10 04:45:05,752 DEBUG 2024-04-10 04:45:05,752 INFO 2024-04-10 04:45:05,752 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-3500/rc.txt 2024-04-10 04:45:06,594 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 04:45:06,595 DEBUG --- stdout --- 2024-04-10 04:45:06,596 DEBUG 4 /results/orders/authn-users-3500/rc.txt 2024-04-10 04:45:06,596 DEBUG --- stderr --- 2024-04-10 04:45:06,596 DEBUG 2024-04-10 04:45:06,596 INFO - Download file (size 4k) 2024-04-10 04:45:06,596 INFO 2024-04-10 04:45:06,597 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-10 04:45:06,597 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:06,970 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:06,970 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:06,970 DEBUG --- stdout --- 2024-04-10 04:45:06,970 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:06,970 DEBUG --- stderr --- 2024-04-10 04:45:06,970 DEBUG source : /results/orders/authn-users-3500/rc.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/rc.txt 2024-04-10 04:45:06,970 INFO 2024-04-10 04:45:06,971 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-10 04:45:06,971 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:07,335 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:07,336 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:07,336 DEBUG --- stdout --- 2024-04-10 04:45:07,336 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:07,336 DEBUG --- stderr --- 2024-04-10 04:45:07,336 DEBUG 2024-04-10 04:45:07,336 INFO 2024-04-10 04:45:07,336 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-3500/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/rc.txt 2024-04-10 04:45:07,336 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 04:45:08,166 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:08,166 DEBUG --- stdout --- 2024-04-10 04:45:08,167 DEBUG tar: Removing leading `/' from member names 2024-04-10 04:45:08,167 DEBUG --- stderr --- 2024-04-10 04:45:08,167 DEBUG 2024-04-10 04:45:08,167 INFO 2024-04-10 04:45:08,167 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-10 04:45:08,167 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:08,501 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:08,501 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:08,501 DEBUG --- stdout --- 2024-04-10 04:45:08,501 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:08,502 DEBUG --- stderr --- 2024-04-10 04:45:08,502 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 04:45:08,502 INFO 2024-04-10 04:45:08,502 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-10 04:45:08,502 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:08,839 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:08,840 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:08,840 DEBUG --- stdout --- 2024-04-10 04:45:08,840 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:08,840 DEBUG --- stderr --- 2024-04-10 04:45:08,840 DEBUG 2024-04-10 04:45:08,841 INFO 2024-04-10 04:45:08,841 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3500/stderr.txt" ]; then echo "/results/orders/authn-users-3500/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-3500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3500/stderr.txt file found" 2024-04-10 04:45:08,841 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 04:45:09,690 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 04:45:09,690 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:09,690 DEBUG --- stdout --- 2024-04-10 04:45:09,690 DEBUG /results/orders/authn-users-3500/stderr.txt file found 2024-04-10 04:45:09,690 DEBUG --- stderr --- 2024-04-10 04:45:09,690 DEBUG 2024-04-10 04:45:09,691 INFO 2024-04-10 04:45:09,691 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-10 04:45:09,691 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:10,011 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:10,011 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:10,011 DEBUG --- stdout --- 2024-04-10 04:45:10,011 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:10,011 DEBUG --- stderr --- 2024-04-10 04:45:10,011 DEBUG 2024-04-10 04:45:10,011 INFO 2024-04-10 04:45:10,011 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-3500/stderr.txt 2024-04-10 04:45:10,837 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 04:45:10,837 DEBUG --- stdout --- 2024-04-10 04:45:10,837 DEBUG 0 /results/orders/authn-users-3500/stderr.txt 2024-04-10 04:45:10,837 DEBUG --- stderr --- 2024-04-10 04:45:10,837 DEBUG 2024-04-10 04:45:10,837 INFO - Download file (size 0k) 2024-04-10 04:45:10,838 INFO 2024-04-10 04:45:10,838 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-10 04:45:10,838 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:11,210 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:11,210 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:11,210 DEBUG --- stdout --- 2024-04-10 04:45:11,210 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:11,211 DEBUG --- stderr --- 2024-04-10 04:45:11,211 DEBUG source : /results/orders/authn-users-3500/stderr.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/stderr.txt 2024-04-10 04:45:11,211 INFO 2024-04-10 04:45:11,211 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-10 04:45:11,211 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:11,591 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:11,591 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:11,591 DEBUG --- stdout --- 2024-04-10 04:45:11,591 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:11,591 DEBUG --- stderr --- 2024-04-10 04:45:11,591 DEBUG 2024-04-10 04:45:11,592 INFO 2024-04-10 04:45:11,592 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-3500/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/stderr.txt 2024-04-10 04:45:11,592 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 04:45:12,369 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:12,369 DEBUG --- stdout --- 2024-04-10 04:45:12,369 DEBUG tar: Removing leading `/' from member names 2024-04-10 04:45:12,369 DEBUG --- stderr --- 2024-04-10 04:45:12,369 DEBUG 2024-04-10 04:45:12,370 INFO 2024-04-10 04:45:12,370 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 04:45:12,370 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:12,778 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:12,778 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:12,778 DEBUG --- stdout --- 2024-04-10 04:45:12,778 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:12,778 DEBUG --- stderr --- 2024-04-10 04:45:12,778 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 04:45:12,779 INFO 2024-04-10 04:45:12,779 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-10 04:45:12,779 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:13,174 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:13,175 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:13,175 DEBUG --- stdout --- 2024-04-10 04:45:13,175 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:13,175 DEBUG --- stderr --- 2024-04-10 04:45:13,175 DEBUG 2024-04-10 04:45:13,176 INFO 2024-04-10 04:45:13,176 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3500/stdout.txt" ]; then echo "/results/orders/authn-users-3500/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-3500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3500/stdout.txt file found" 2024-04-10 04:45:13,176 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 04:45:14,118 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 04:45:14,118 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:14,118 DEBUG --- stdout --- 2024-04-10 04:45:14,118 DEBUG /results/orders/authn-users-3500/stdout.txt file found 2024-04-10 04:45:14,118 DEBUG --- stderr --- 2024-04-10 04:45:14,118 DEBUG 2024-04-10 04:45:14,119 INFO 2024-04-10 04:45:14,119 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-10 04:45:14,119 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:14,477 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:14,477 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:14,477 DEBUG --- stdout --- 2024-04-10 04:45:14,477 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:14,477 DEBUG --- stderr --- 2024-04-10 04:45:14,477 DEBUG 2024-04-10 04:45:14,477 INFO 2024-04-10 04:45:14,477 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-3500/stdout.txt 2024-04-10 04:45:15,310 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 04:45:15,310 DEBUG --- stdout --- 2024-04-10 04:45:15,310 DEBUG 508 /results/orders/authn-users-3500/stdout.txt 2024-04-10 04:45:15,311 DEBUG --- stderr --- 2024-04-10 04:45:15,311 DEBUG 2024-04-10 04:45:15,311 INFO - Download file (size 508k) 2024-04-10 04:45:15,311 INFO 2024-04-10 04:45:15,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-10 04:45:15,311 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:15,636 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:15,636 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:15,636 DEBUG --- stdout --- 2024-04-10 04:45:15,636 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:15,636 DEBUG --- stderr --- 2024-04-10 04:45:15,636 DEBUG source : /results/orders/authn-users-3500/stdout.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/stdout.txt 2024-04-10 04:45:15,637 INFO 2024-04-10 04:45:15,637 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-10 04:45:15,637 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:15,972 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:15,972 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:15,972 DEBUG --- stdout --- 2024-04-10 04:45:15,972 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:15,972 DEBUG --- stderr --- 2024-04-10 04:45:15,972 DEBUG 2024-04-10 04:45:15,973 INFO 2024-04-10 04:45:15,973 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-3500/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/stdout.txt 2024-04-10 04:45:15,973 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 04:45:17,063 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:17,063 DEBUG --- stdout --- 2024-04-10 04:45:17,063 DEBUG tar: Removing leading `/' from member names 2024-04-10 04:45:17,063 DEBUG --- stderr --- 2024-04-10 04:45:17,063 DEBUG ________________________________________________________________________________ [2024-04-10 04:45:17] authn-users-3500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-10 04:45:17,064 INFO 2024-04-10 04:45:17,064 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-10 04:45:17,064 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:17,409 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:17,409 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:17,410 DEBUG --- stdout --- 2024-04-10 04:45:17,410 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:17,410 DEBUG --- stderr --- 2024-04-10 04:45:17,410 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 04:45:17,410 INFO 2024-04-10 04:45:17,411 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-10 04:45:17,411 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:17,791 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:17,791 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:17,791 DEBUG --- stdout --- 2024-04-10 04:45:17,791 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:17,791 DEBUG --- stderr --- 2024-04-10 04:45:17,791 DEBUG 2024-04-10 04:45:17,792 INFO 2024-04-10 04:45:17,792 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3500/cmd.txt" ]; then echo "/results/orders/authn-users-3500/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-3500/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3500/cmd.txt file found" 2024-04-10 04:45:17,792 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 04:45:18,664 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 04:45:18,664 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:18,664 DEBUG --- stdout --- 2024-04-10 04:45:18,664 DEBUG /results/orders/authn-users-3500/cmd.txt file found 2024-04-10 04:45:18,664 DEBUG --- stderr --- 2024-04-10 04:45:18,665 DEBUG 2024-04-10 04:45:18,665 INFO 2024-04-10 04:45:18,665 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-10 04:45:18,665 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:18,980 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:18,981 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:18,981 DEBUG --- stdout --- 2024-04-10 04:45:18,981 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:18,981 DEBUG --- stderr --- 2024-04-10 04:45:18,981 DEBUG 2024-04-10 04:45:18,981 INFO 2024-04-10 04:45:18,981 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-3500/cmd.txt 2024-04-10 04:45:19,838 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 04:45:19,838 DEBUG --- stdout --- 2024-04-10 04:45:19,838 DEBUG 4 /results/orders/authn-users-3500/cmd.txt 2024-04-10 04:45:19,838 DEBUG --- stderr --- 2024-04-10 04:45:19,838 DEBUG 2024-04-10 04:45:19,838 INFO - Download file (size 4k) 2024-04-10 04:45:19,839 INFO 2024-04-10 04:45:19,839 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-10 04:45:19,839 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:20,160 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:20,160 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:20,160 DEBUG --- stdout --- 2024-04-10 04:45:20,160 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:20,160 DEBUG --- stderr --- 2024-04-10 04:45:20,160 DEBUG source : /results/orders/authn-users-3500/cmd.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/cmd.txt 2024-04-10 04:45:20,160 INFO 2024-04-10 04:45:20,160 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-10 04:45:20,160 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 04:45:20,467 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 04:45:20,467 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:20,467 DEBUG --- stdout --- 2024-04-10 04:45:20,468 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 04:45:20,468 DEBUG --- stderr --- 2024-04-10 04:45:20,468 DEBUG 2024-04-10 04:45:20,468 INFO 2024-04-10 04:45:20,468 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-3500/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/cmd.txt 2024-04-10 04:45:20,468 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 04:45:21,252 INFO [loop_until]: OK (rc = 0) 2024-04-10 04:45:21,252 DEBUG --- stdout --- 2024-04-10 04:45:21,252 DEBUG tar: Removing leading `/' from member names 2024-04-10 04:45:21,252 DEBUG --- stderr --- 2024-04-10 04:45:21,252 DEBUG 2024-04-10 04:45:21,253 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/cmd.txt is small (1 lines), printing all lines: 2024-04-10 04:45:21,253 DEBUG ----- output ----- 2024-04-10 04:45:21,253 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-3500 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-10 04:45:21,253 INFO ________________________________________________________________________________ [2024-04-10 04:45:21] authn-users-3500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-10 04:45:21,254 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/rc.txt is small (1 lines), printing all lines: 2024-04-10 04:45:21,254 DEBUG ----- output ----- 2024-04-10 04:45:21,254 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-10 04:45:21,254 INFO ________________________________________________________________________________ [2024-04-10 04:45:21] authn-users-3500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-10 04:45:21,255 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/06_authn-users-3500/stdout.txt is 8746 lines): 2024-04-10 04:45:21,255 DEBUG ----- output ----- 2024-04-10 04:45:21,255 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-10 04:45:21,255 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-10 04:45:21,255 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-10 04:45:21,255 INFO 2024-04-10 04:45:21,255 INFO ================================================================================ 2024-04-10 04:45:21,255 INFO 2024-04-10 04:42:51 5s elapsed 2024-04-10 04:45:21,256 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,256 INFO > Global (OK=1461 KO=0 ) 2024-04-10 04:45:21,256 INFO > userRestLogin (OK=1461 KO=0 ) 2024-04-10 04:45:21,256 INFO 2024-04-10 04:45:21,256 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,256 INFO [- ] 0% 2024-04-10 04:45:21,256 INFO waiting: 12645281 / active: 7 / done: 1461 2024-04-10 04:45:21,256 INFO ================================================================================ 2024-04-10 04:45:21,256 INFO 2024-04-10 04:45:21,256 INFO 2024-04-10 04:45:21,256 INFO ================================================================================ 2024-04-10 04:45:21,256 INFO 2024-04-10 04:42:56 10s elapsed 2024-04-10 04:45:21,256 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,256 INFO > Global (OK=5837 KO=0 ) 2024-04-10 04:45:21,256 INFO > userRestLogin (OK=5837 KO=0 ) 2024-04-10 04:45:21,256 INFO 2024-04-10 04:45:21,256 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,256 INFO [- ] 0% 2024-04-10 04:45:21,256 INFO waiting: 12640898 / active: 14 / done: 5837 2024-04-10 04:45:21,256 INFO ================================================================================ 2024-04-10 04:45:21,256 INFO 2024-04-10 04:45:21,256 INFO 2024-04-10 04:45:21,256 INFO ================================================================================ 2024-04-10 04:45:21,256 INFO 2024-04-10 04:43:01 15s elapsed 2024-04-10 04:45:21,256 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,256 INFO > Global (OK=13125 KO=0 ) 2024-04-10 04:45:21,256 INFO > userRestLogin (OK=13125 KO=0 ) 2024-04-10 04:45:21,256 INFO 2024-04-10 04:45:21,256 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,256 INFO [- ] 0% 2024-04-10 04:45:21,256 INFO waiting: 12633596 / active: 29 / done: 13124 2024-04-10 04:45:21,256 INFO ================================================================================ 2024-04-10 04:45:21,256 INFO 2024-04-10 04:45:21,256 INFO 2024-04-10 04:45:21,256 INFO ================================================================================ 2024-04-10 04:45:21,256 INFO 2024-04-10 04:43:06 20s elapsed 2024-04-10 04:45:21,256 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,256 INFO > Global (OK=23321 KO=0 ) 2024-04-10 04:45:21,256 INFO > userRestLogin (OK=23321 KO=0 ) 2024-04-10 04:45:21,256 INFO 2024-04-10 04:45:21,257 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,257 INFO [- ] 0% 2024-04-10 04:45:21,257 INFO waiting: 12623382 / active: 46 / done: 23321 2024-04-10 04:45:21,257 INFO ================================================================================ 2024-04-10 04:45:21,257 INFO 2024-04-10 04:45:21,257 INFO 2024-04-10 04:45:21,257 INFO ================================================================================ 2024-04-10 04:45:21,257 INFO 2024-04-10 04:43:11 25s elapsed 2024-04-10 04:45:21,257 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,257 INFO > Global (OK=36434 KO=0 ) 2024-04-10 04:45:21,257 INFO > userRestLogin (OK=36434 KO=0 ) 2024-04-10 04:45:21,257 INFO 2024-04-10 04:45:21,257 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,257 INFO [- ] 0% 2024-04-10 04:45:21,257 INFO waiting: 12610249 / active: 66 / done: 36434 2024-04-10 04:45:21,257 INFO ================================================================================ 2024-04-10 04:45:21,257 INFO 2024-04-10 04:45:21,257 INFO 2024-04-10 04:45:21,257 INFO ================================================================================ 2024-04-10 04:45:21,257 INFO 2024-04-10 04:43:16 30s elapsed 2024-04-10 04:45:21,257 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,257 INFO > Global (OK=52400 KO=0 ) 2024-04-10 04:45:21,257 INFO > userRestLogin (OK=52400 KO=0 ) 2024-04-10 04:45:21,257 INFO 2024-04-10 04:45:21,257 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,257 INFO [- ] 0% 2024-04-10 04:45:21,257 INFO waiting: 12594298 / active: 64 / done: 52387 2024-04-10 04:45:21,257 INFO ================================================================================ 2024-04-10 04:45:21,257 INFO 2024-04-10 04:45:21,257 INFO 2024-04-10 04:45:21,257 INFO ================================================================================ 2024-04-10 04:45:21,257 INFO 2024-04-10 04:43:21 35s elapsed 2024-04-10 04:45:21,257 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,257 INFO > Global (OK=69930 KO=0 ) 2024-04-10 04:45:21,257 INFO > userRestLogin (OK=69930 KO=0 ) 2024-04-10 04:45:21,257 INFO 2024-04-10 04:45:21,257 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,257 INFO [- ] 0% 2024-04-10 04:45:21,257 INFO waiting: 12576693 / active: 126 / done: 69930 2024-04-10 04:45:21,257 INFO ================================================================================ 2024-04-10 04:45:21,257 INFO 2024-04-10 04:45:21,258 INFO 2024-04-10 04:45:21,258 INFO ================================================================================ 2024-04-10 04:45:21,258 INFO 2024-04-10 04:43:26 40s elapsed 2024-04-10 04:45:21,258 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,258 INFO > Global (OK=87417 KO=0 ) 2024-04-10 04:45:21,258 INFO > userRestLogin (OK=87417 KO=0 ) 2024-04-10 04:45:21,258 INFO 2024-04-10 04:45:21,258 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,258 INFO [- ] 0% 2024-04-10 04:45:21,258 INFO waiting: 12559262 / active: 71 / done: 87416 2024-04-10 04:45:21,258 INFO ================================================================================ 2024-04-10 04:45:21,258 INFO 2024-04-10 04:45:21,258 INFO 2024-04-10 04:45:21,258 INFO [...] 2024-04-10 04:45:21,260 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,260 INFO > Global (OK=12559878 KO=0 ) 2024-04-10 04:45:21,260 INFO > userRestLogin (OK=12559878 KO=0 ) 2024-04-10 04:45:21,260 INFO 2024-04-10 04:45:21,260 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,260 INFO [#########################################################################-] 99% 2024-04-10 04:45:21,260 INFO waiting: 86784 / active: 87 / done: 12559878 2024-04-10 04:45:21,260 INFO ================================================================================ 2024-04-10 04:45:21,260 INFO 2024-04-10 04:45:21,260 INFO 2024-04-10 04:45:21,260 INFO ================================================================================ 2024-04-10 04:45:21,260 INFO 2024-04-10 05:42:56 3610s elapsed 2024-04-10 04:45:21,260 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,260 INFO > Global (OK=12577233 KO=0 ) 2024-04-10 04:45:21,261 INFO > userRestLogin (OK=12577233 KO=0 ) 2024-04-10 04:45:21,261 INFO 2024-04-10 04:45:21,261 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,261 INFO [#########################################################################-] 99% 2024-04-10 04:45:21,261 INFO waiting: 69422 / active: 94 / done: 12577233 2024-04-10 04:45:21,261 INFO ================================================================================ 2024-04-10 04:45:21,261 INFO 2024-04-10 04:45:21,261 INFO 2024-04-10 04:45:21,261 INFO ================================================================================ 2024-04-10 04:45:21,261 INFO 2024-04-10 05:43:01 3615s elapsed 2024-04-10 04:45:21,261 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,261 INFO > Global (OK=12594528 KO=0 ) 2024-04-10 04:45:21,261 INFO > userRestLogin (OK=12594528 KO=0 ) 2024-04-10 04:45:21,261 INFO 2024-04-10 04:45:21,261 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,261 INFO [#########################################################################-] 99% 2024-04-10 04:45:21,261 INFO waiting: 52109 / active: 112 / done: 12594528 2024-04-10 04:45:21,261 INFO ================================================================================ 2024-04-10 04:45:21,261 INFO 2024-04-10 04:45:21,261 INFO 2024-04-10 04:45:21,261 INFO ================================================================================ 2024-04-10 04:45:21,261 INFO 2024-04-10 05:43:06 3620s elapsed 2024-04-10 04:45:21,261 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,261 INFO > Global (OK=12612123 KO=0 ) 2024-04-10 04:45:21,261 INFO > userRestLogin (OK=12612123 KO=0 ) 2024-04-10 04:45:21,261 INFO 2024-04-10 04:45:21,261 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,261 INFO [#########################################################################-] 99% 2024-04-10 04:45:21,261 INFO waiting: 34544 / active: 82 / done: 12612123 2024-04-10 04:45:21,261 INFO ================================================================================ 2024-04-10 04:45:21,261 INFO 2024-04-10 04:45:21,261 INFO 2024-04-10 04:45:21,261 INFO ================================================================================ 2024-04-10 04:45:21,261 INFO 2024-04-10 05:43:11 3625s elapsed 2024-04-10 04:45:21,261 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,261 INFO > Global (OK=12629410 KO=0 ) 2024-04-10 04:45:21,261 INFO > userRestLogin (OK=12629410 KO=0 ) 2024-04-10 04:45:21,261 INFO 2024-04-10 04:45:21,261 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,261 INFO [#########################################################################-] 99% 2024-04-10 04:45:21,262 INFO waiting: 17247 / active: 92 / done: 12629410 2024-04-10 04:45:21,262 INFO ================================================================================ 2024-04-10 04:45:21,262 INFO 2024-04-10 04:45:21,262 INFO 2024-04-10 04:45:21,262 INFO ================================================================================ 2024-04-10 04:45:21,262 INFO 2024-04-10 05:43:16 3630s elapsed 2024-04-10 04:45:21,262 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,262 INFO > Global (OK=12646708 KO=0 ) 2024-04-10 04:45:21,262 INFO > userRestLogin (OK=12646708 KO=0 ) 2024-04-10 04:45:21,262 INFO 2024-04-10 04:45:21,262 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,262 INFO [#########################################################################-] 99% 2024-04-10 04:45:21,262 INFO waiting: 0 / active: 41 / done: 12646708 2024-04-10 04:45:21,262 INFO ================================================================================ 2024-04-10 04:45:21,262 INFO 2024-04-10 04:45:21,262 INFO 2024-04-10 04:45:21,262 INFO ================================================================================ 2024-04-10 04:45:21,262 INFO 2024-04-10 05:43:16 3630s elapsed 2024-04-10 04:45:21,262 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 04:45:21,262 INFO > Global (OK=12646749 KO=0 ) 2024-04-10 04:45:21,262 INFO > userRestLogin (OK=12646749 KO=0 ) 2024-04-10 04:45:21,262 INFO 2024-04-10 04:45:21,262 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 04:45:21,262 INFO [##########################################################################]100% 2024-04-10 04:45:21,262 INFO waiting: 0 / active: 0 / done: 12646749 2024-04-10 04:45:21,262 INFO ================================================================================ 2024-04-10 04:45:21,262 INFO 2024-04-10 04:45:21,262 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-10 04:45:21,262 INFO 2024-04-10 04:45:21,262 INFO ---------------------- 2024-04-10 04:45:21,262 INFO [10/Apr/2024 05:43:16] Create tar file authn-users-3500.tar.gz 2024-04-10 04:45:21,262 INFO ---------------------- 2024-04-10 04:45:21,262 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-10 04:45:21,262 INFO RESULT_FOLDER=authn-users-3500 2024-04-10 04:45:21,262 INFO SIMULATION_PATH=authn-users-3500/loadtest-20240410034245997 2024-04-10 04:45:21,262 INFO PWD=/results/gatling/authn-users-3500/loadtest-20240410034245997 2024-04-10 04:45:21,262 INFO [cmd] tar -czvf /results/gatling//authn-users-3500.tar.gz --exclude=simulation.log * 2024-04-10 04:45:21,262 INFO /results/gatling 2024-04-10 04:45:21,263 INFO 2024-04-10 04:45:21,263 INFO ---------------------- 2024-04-10 04:45:21,263 INFO [10/Apr/2024 05:43:16] Update /results/gatling//latest 2024-04-10 04:45:21,263 INFO ---------------------- 2024-04-10 04:45:21,263 INFO 2024-04-10 04:45:21,263 INFO ---------------------- 2024-04-10 04:45:21,263 INFO [10/Apr/2024 05:43:16] Update /results/gatling//latest.tar.gz 2024-04-10 04:45:21,263 INFO ---------------------- 2024-04-10 04:45:21,263 INFO ________________________________________________________________________________ [2024-04-10 04:45:21] authn-users-3500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-10 04:45:21,263 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-3500/stderr.txt is small (0 lines), printing all lines: 2024-04-10 04:45:21,263 DEBUG ----- output ----- 2024-04-10 04:45:21,263 DEBUG 2024-04-10 04:45:21,263 INFO ________________________________________________________________________________ [2024-04-10 04:45:21] authn-users-3500 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-10 04:45:21] authn-users-3500 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-10 04:45:21] authn-users-3500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped