--Task-- name: authn-users-6500 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-6000'] preceding_task: authn-users-6000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 6500, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-19 08:55:37] authn-users-6500 pre : N/A ________________________________________________________________________________ [2024-04-19 08:55:37] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-19 08:55:37] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-6500/stdout.txt Pod log : /results/orders/authn-users-6500 2024-04-19 08:55:37,136 INFO 2024-04-19 08:55:37,136 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-19 08:55:37,136 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:37,445 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:37,446 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:37,446 DEBUG --- stdout --- 2024-04-19 08:55:37,446 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:37,446 DEBUG --- stderr --- 2024-04-19 08:55:37,446 DEBUG ________________________________________________________________________________ [2024-04-19 08:55:37] authn-users-6500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-19 08:55:37,446 INFO 2024-04-19 08:55:37,446 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-19 08:55:37,446 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:37,745 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:37,745 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:37,745 DEBUG --- stdout --- 2024-04-19 08:55:37,745 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:37,745 DEBUG --- stderr --- 2024-04-19 08:55:37,745 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-6500 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-19 08:55:37] authn-users-6500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-19 08:55:37,746 INFO 2024-04-19 08:55:37,746 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-19 08:55:37,746 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:38,046 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:38,046 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:38,046 DEBUG --- stdout --- 2024-04-19 08:55:38,046 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:38,046 DEBUG --- stderr --- 2024-04-19 08:55:38,046 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/order.json { "name": "authn-users-6500", "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-6500 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-6500", "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=6500 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-19 08:55:38] authn-users-6500 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-19 08:55:38,048 INFO 2024-04-19 08:55:38,048 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-19 08:55:38,048 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 08:55:38,279 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 08:55:38,280 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:38,280 DEBUG --- stdout --- 2024-04-19 08:55:38,280 DEBUG WAITING 2024-04-19 08:55:38,280 DEBUG --- stderr --- 2024-04-19 08:55:38,280 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-19 08:55:38] authn-users-6500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-19 08:55:38,280 INFO 2024-04-19 08:55:38,280 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-19 08:55:38,280 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:38,581 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:38,581 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:38,581 DEBUG --- stdout --- 2024-04-19 08:55:38,581 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:38,581 DEBUG --- stderr --- 2024-04-19 08:55:38,581 DEBUG 2024-04-19 08:55:38,582 INFO 2024-04-19 08:55:38,582 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/order.json overseer-0-5c67bf94db-77mh9:/results/orders/order.json 2024-04-19 08:55:38,582 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 08:55:39,949 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:39,949 DEBUG --- stdout --- 2024-04-19 08:55:39,949 DEBUG 2024-04-19 08:55:39,949 DEBUG --- stderr --- 2024-04-19 08:55:39,950 DEBUG Order has been successfully forwarded to overseer [2024-04-19 08:55:39] - INFO: Check if Gatling simulation started successfully 2024-04-19 08:55:39,950 INFO 2024-04-19 08:55:39,950 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-19 08:55:39,950 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:40,282 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:40,283 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:40,283 DEBUG --- stdout --- 2024-04-19 08:55:40,283 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:40,283 DEBUG --- stderr --- 2024-04-19 08:55:40,283 DEBUG 2024-04-19 08:55:40,283 INFO 2024-04-19 08:55:40,283 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 -- head -n 25 /results/orders/authn-users-6500/stdout.txt | grep "started..." 2024-04-19 08:55:40,283 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 08:55:41,043 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-19 08:55:46,794 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-19 08:55:52,581 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-19 08:55:52,581 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:52,581 DEBUG --- stdout --- 2024-04-19 08:55:52,581 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-19 08:55:52,581 DEBUG --- stderr --- 2024-04-19 08:55:52,581 DEBUG ________________________________________________________________________________ [2024-04-19 08:55:52] authn-users-6500 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 08s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 09s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 10s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-19 09:58:03] authn-users-6500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-19 09:58:03,414 INFO 2024-04-19 09:58:03,415 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-19 09:58:03,415 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:03,802 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:03,802 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:03,802 DEBUG --- stdout --- 2024-04-19 09:58:03,802 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:03,802 DEBUG --- stderr --- 2024-04-19 09:58:03,802 DEBUG 2024-04-19 09:58:03,803 INFO 2024-04-19 09:58:03,803 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 -- rm -f /results/orders/order.json 2024-04-19 09:58:03,803 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 09:58:04,681 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:04,681 DEBUG --- stdout --- 2024-04-19 09:58:04,681 DEBUG 2024-04-19 09:58:04,681 DEBUG --- stderr --- 2024-04-19 09:58:04,681 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-19 09:58:04] authn-users-6500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-19 09:58:04] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/order.json 2024-04-19 09:58:04,682 INFO 2024-04-19 09:58:04,682 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-19 09:58:04,682 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:04,999 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:04,999 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:04,999 DEBUG --- stdout --- 2024-04-19 09:58:04,999 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:04,999 DEBUG --- stderr --- 2024-04-19 09:58:04,999 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 09:58:05,000 INFO 2024-04-19 09:58:05,000 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-19 09:58:05,000 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:05,306 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:05,306 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:05,307 DEBUG --- stdout --- 2024-04-19 09:58:05,307 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:05,307 DEBUG --- stderr --- 2024-04-19 09:58:05,307 DEBUG 2024-04-19 09:58:05,307 INFO 2024-04-19 09:58:05,307 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6500/rc.txt" ]; then echo "/results/orders/authn-users-6500/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-6500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6500/rc.txt file found" 2024-04-19 09:58:05,307 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 09:58:06,150 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 09:58:06,151 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:06,151 DEBUG --- stdout --- 2024-04-19 09:58:06,151 DEBUG /results/orders/authn-users-6500/rc.txt file found 2024-04-19 09:58:06,151 DEBUG --- stderr --- 2024-04-19 09:58:06,151 DEBUG 2024-04-19 09:58:06,151 INFO 2024-04-19 09:58:06,151 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-19 09:58:06,151 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:06,466 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:06,466 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:06,466 DEBUG --- stdout --- 2024-04-19 09:58:06,466 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:06,466 DEBUG --- stderr --- 2024-04-19 09:58:06,466 DEBUG 2024-04-19 09:58:06,466 INFO 2024-04-19 09:58:06,467 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-6500/rc.txt 2024-04-19 09:58:07,312 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 09:58:07,312 DEBUG --- stdout --- 2024-04-19 09:58:07,312 DEBUG 4 /results/orders/authn-users-6500/rc.txt 2024-04-19 09:58:07,312 DEBUG --- stderr --- 2024-04-19 09:58:07,312 DEBUG 2024-04-19 09:58:07,312 INFO - Download file (size 4k) 2024-04-19 09:58:07,313 INFO 2024-04-19 09:58:07,313 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-19 09:58:07,313 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:07,625 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:07,625 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:07,625 DEBUG --- stdout --- 2024-04-19 09:58:07,625 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:07,625 DEBUG --- stderr --- 2024-04-19 09:58:07,625 DEBUG source : /results/orders/authn-users-6500/rc.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/rc.txt 2024-04-19 09:58:07,626 INFO 2024-04-19 09:58:07,626 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 09:58:07,626 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:07,942 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:07,943 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:07,943 DEBUG --- stdout --- 2024-04-19 09:58:07,943 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:07,943 DEBUG --- stderr --- 2024-04-19 09:58:07,943 DEBUG 2024-04-19 09:58:07,944 INFO 2024-04-19 09:58:07,944 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-6500/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/rc.txt 2024-04-19 09:58:07,944 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 09:58:08,884 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:08,884 DEBUG --- stdout --- 2024-04-19 09:58:08,884 DEBUG tar: Removing leading `/' from member names 2024-04-19 09:58:08,884 DEBUG --- stderr --- 2024-04-19 09:58:08,884 DEBUG 2024-04-19 09:58:08,885 INFO 2024-04-19 09:58:08,885 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-19 09:58:08,885 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:09,183 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:09,184 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:09,184 DEBUG --- stdout --- 2024-04-19 09:58:09,184 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:09,184 DEBUG --- stderr --- 2024-04-19 09:58:09,184 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 09:58:09,184 INFO 2024-04-19 09:58:09,184 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-19 09:58:09,184 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:09,500 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:09,500 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:09,500 DEBUG --- stdout --- 2024-04-19 09:58:09,500 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:09,500 DEBUG --- stderr --- 2024-04-19 09:58:09,500 DEBUG 2024-04-19 09:58:09,501 INFO 2024-04-19 09:58:09,501 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6500/stderr.txt" ]; then echo "/results/orders/authn-users-6500/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-6500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6500/stderr.txt file found" 2024-04-19 09:58:09,501 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 09:58:10,333 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 09:58:10,335 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:10,335 DEBUG --- stdout --- 2024-04-19 09:58:10,335 DEBUG /results/orders/authn-users-6500/stderr.txt file found 2024-04-19 09:58:10,335 DEBUG --- stderr --- 2024-04-19 09:58:10,335 DEBUG 2024-04-19 09:58:10,335 INFO 2024-04-19 09:58:10,336 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-19 09:58:10,336 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:10,696 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:10,697 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:10,697 DEBUG --- stdout --- 2024-04-19 09:58:10,697 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:10,697 DEBUG --- stderr --- 2024-04-19 09:58:10,697 DEBUG 2024-04-19 09:58:10,697 INFO 2024-04-19 09:58:10,697 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-6500/stderr.txt 2024-04-19 09:58:11,512 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 09:58:11,512 DEBUG --- stdout --- 2024-04-19 09:58:11,512 DEBUG 0 /results/orders/authn-users-6500/stderr.txt 2024-04-19 09:58:11,512 DEBUG --- stderr --- 2024-04-19 09:58:11,512 DEBUG 2024-04-19 09:58:11,512 INFO - Download file (size 0k) 2024-04-19 09:58:11,513 INFO 2024-04-19 09:58:11,513 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-19 09:58:11,513 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:11,828 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:11,828 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:11,828 DEBUG --- stdout --- 2024-04-19 09:58:11,828 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:11,828 DEBUG --- stderr --- 2024-04-19 09:58:11,829 DEBUG source : /results/orders/authn-users-6500/stderr.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/stderr.txt 2024-04-19 09:58:11,829 INFO 2024-04-19 09:58:11,829 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-19 09:58:11,829 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:12,148 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:12,148 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:12,148 DEBUG --- stdout --- 2024-04-19 09:58:12,148 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:12,148 DEBUG --- stderr --- 2024-04-19 09:58:12,148 DEBUG 2024-04-19 09:58:12,149 INFO 2024-04-19 09:58:12,149 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-6500/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/stderr.txt 2024-04-19 09:58:12,149 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 09:58:12,909 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:12,909 DEBUG --- stdout --- 2024-04-19 09:58:12,909 DEBUG tar: Removing leading `/' from member names 2024-04-19 09:58:12,909 DEBUG --- stderr --- 2024-04-19 09:58:12,909 DEBUG 2024-04-19 09:58:12,909 INFO 2024-04-19 09:58:12,909 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-19 09:58:12,910 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:13,215 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:13,215 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:13,215 DEBUG --- stdout --- 2024-04-19 09:58:13,215 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:13,215 DEBUG --- stderr --- 2024-04-19 09:58:13,215 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 09:58:13,216 INFO 2024-04-19 09:58:13,216 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-19 09:58:13,216 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:13,515 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:13,515 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:13,515 DEBUG --- stdout --- 2024-04-19 09:58:13,515 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:13,515 DEBUG --- stderr --- 2024-04-19 09:58:13,515 DEBUG 2024-04-19 09:58:13,516 INFO 2024-04-19 09:58:13,516 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6500/stdout.txt" ]; then echo "/results/orders/authn-users-6500/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-6500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6500/stdout.txt file found" 2024-04-19 09:58:13,516 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 09:58:14,351 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 09:58:14,351 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:14,351 DEBUG --- stdout --- 2024-04-19 09:58:14,351 DEBUG /results/orders/authn-users-6500/stdout.txt file found 2024-04-19 09:58:14,351 DEBUG --- stderr --- 2024-04-19 09:58:14,351 DEBUG 2024-04-19 09:58:14,351 INFO 2024-04-19 09:58:14,351 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-19 09:58:14,351 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:14,664 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:14,665 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:14,665 DEBUG --- stdout --- 2024-04-19 09:58:14,665 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:14,665 DEBUG --- stderr --- 2024-04-19 09:58:14,665 DEBUG 2024-04-19 09:58:14,665 INFO 2024-04-19 09:58:14,665 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-6500/stdout.txt 2024-04-19 09:58:15,487 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 09:58:15,489 DEBUG --- stdout --- 2024-04-19 09:58:15,489 DEBUG 508 /results/orders/authn-users-6500/stdout.txt 2024-04-19 09:58:15,489 DEBUG --- stderr --- 2024-04-19 09:58:15,489 DEBUG 2024-04-19 09:58:15,489 INFO - Download file (size 508k) 2024-04-19 09:58:15,490 INFO 2024-04-19 09:58:15,490 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-19 09:58:15,490 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:15,824 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:15,824 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:15,824 DEBUG --- stdout --- 2024-04-19 09:58:15,824 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:15,824 DEBUG --- stderr --- 2024-04-19 09:58:15,824 DEBUG source : /results/orders/authn-users-6500/stdout.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/stdout.txt 2024-04-19 09:58:15,825 INFO 2024-04-19 09:58:15,825 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-19 09:58:15,825 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:16,158 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:16,158 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:16,158 DEBUG --- stdout --- 2024-04-19 09:58:16,159 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:16,159 DEBUG --- stderr --- 2024-04-19 09:58:16,159 DEBUG 2024-04-19 09:58:16,159 INFO 2024-04-19 09:58:16,159 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-6500/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/stdout.txt 2024-04-19 09:58:16,159 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 09:58:17,243 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:17,243 DEBUG --- stdout --- 2024-04-19 09:58:17,243 DEBUG tar: Removing leading `/' from member names 2024-04-19 09:58:17,243 DEBUG --- stderr --- 2024-04-19 09:58:17,243 DEBUG ________________________________________________________________________________ [2024-04-19 09:58:17] authn-users-6500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-19 09:58:17,244 INFO 2024-04-19 09:58:17,244 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-19 09:58:17,244 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:17,542 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:17,543 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:17,543 DEBUG --- stdout --- 2024-04-19 09:58:17,543 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:17,543 DEBUG --- stderr --- 2024-04-19 09:58:17,543 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 09:58:17,543 INFO 2024-04-19 09:58:17,543 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-19 09:58:17,543 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:17,859 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:17,860 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:17,860 DEBUG --- stdout --- 2024-04-19 09:58:17,860 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:17,860 DEBUG --- stderr --- 2024-04-19 09:58:17,860 DEBUG 2024-04-19 09:58:17,861 INFO 2024-04-19 09:58:17,861 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6500/cmd.txt" ]; then echo "/results/orders/authn-users-6500/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-6500/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6500/cmd.txt file found" 2024-04-19 09:58:17,861 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 09:58:18,697 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 09:58:18,698 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:18,698 DEBUG --- stdout --- 2024-04-19 09:58:18,698 DEBUG /results/orders/authn-users-6500/cmd.txt file found 2024-04-19 09:58:18,698 DEBUG --- stderr --- 2024-04-19 09:58:18,698 DEBUG 2024-04-19 09:58:18,698 INFO 2024-04-19 09:58:18,698 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-19 09:58:18,698 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:19,018 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:19,018 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:19,018 DEBUG --- stdout --- 2024-04-19 09:58:19,018 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:19,018 DEBUG --- stderr --- 2024-04-19 09:58:19,018 DEBUG 2024-04-19 09:58:19,019 INFO 2024-04-19 09:58:19,019 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-6500/cmd.txt 2024-04-19 09:58:19,853 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 09:58:19,854 DEBUG --- stdout --- 2024-04-19 09:58:19,854 DEBUG 4 /results/orders/authn-users-6500/cmd.txt 2024-04-19 09:58:19,854 DEBUG --- stderr --- 2024-04-19 09:58:19,854 DEBUG 2024-04-19 09:58:19,854 INFO - Download file (size 4k) 2024-04-19 09:58:19,854 INFO 2024-04-19 09:58:19,854 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-19 09:58:19,855 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:20,203 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:20,203 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:20,203 DEBUG --- stdout --- 2024-04-19 09:58:20,203 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:20,203 DEBUG --- stderr --- 2024-04-19 09:58:20,203 DEBUG source : /results/orders/authn-users-6500/cmd.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/cmd.txt 2024-04-19 09:58:20,203 INFO 2024-04-19 09:58:20,203 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-19 09:58:20,204 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:20,523 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:20,524 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:20,525 DEBUG --- stdout --- 2024-04-19 09:58:20,525 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:20,525 DEBUG --- stderr --- 2024-04-19 09:58:20,525 DEBUG 2024-04-19 09:58:20,526 INFO 2024-04-19 09:58:20,526 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-6500/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/cmd.txt 2024-04-19 09:58:20,526 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 09:58:21,291 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:21,291 DEBUG --- stdout --- 2024-04-19 09:58:21,292 DEBUG tar: Removing leading `/' from member names 2024-04-19 09:58:21,292 DEBUG --- stderr --- 2024-04-19 09:58:21,292 DEBUG 2024-04-19 09:58:21,292 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/cmd.txt is small (1 lines), printing all lines: 2024-04-19 09:58:21,292 DEBUG ----- output ----- 2024-04-19 09:58:21,292 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-6500 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-19 09:58:21,292 INFO ________________________________________________________________________________ [2024-04-19 09:58:21] authn-users-6500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-19 09:58:21,292 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/rc.txt is small (1 lines), printing all lines: 2024-04-19 09:58:21,292 DEBUG ----- output ----- 2024-04-19 09:58:21,293 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-19 09:58:21,293 INFO ________________________________________________________________________________ [2024-04-19 09:58:21] authn-users-6500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-19 09:58:21,294 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/stdout.txt is 8746 lines): 2024-04-19 09:58:21,294 DEBUG ----- output ----- 2024-04-19 09:58:21,294 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-19 09:58:21,294 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-19 09:58:21,294 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 09:58:21,294 INFO 2024-04-19 09:58:21,294 INFO ================================================================================ 2024-04-19 09:58:21,294 INFO 2024-04-19 09:55:53 5s elapsed 2024-04-19 09:58:21,294 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,294 INFO > Global (OK=2714 KO=0 ) 2024-04-19 09:58:21,294 INFO > userRestLogin (OK=2714 KO=0 ) 2024-04-19 09:58:21,295 INFO 2024-04-19 09:58:21,295 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,295 INFO [- ] 0% 2024-04-19 09:58:21,295 INFO waiting: 23501438 / active: 11 / done: 2714 2024-04-19 09:58:21,295 INFO ================================================================================ 2024-04-19 09:58:21,295 INFO 2024-04-19 09:58:21,295 INFO 2024-04-19 09:58:21,295 INFO ================================================================================ 2024-04-19 09:58:21,295 INFO 2024-04-19 09:55:58 10s elapsed 2024-04-19 09:58:21,295 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,295 INFO > Global (OK=10841 KO=0 ) 2024-04-19 09:58:21,295 INFO > userRestLogin (OK=10841 KO=0 ) 2024-04-19 09:58:21,295 INFO 2024-04-19 09:58:21,295 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,295 INFO [- ] 0% 2024-04-19 09:58:21,295 INFO waiting: 23493298 / active: 24 / done: 10841 2024-04-19 09:58:21,295 INFO ================================================================================ 2024-04-19 09:58:21,295 INFO 2024-04-19 09:58:21,295 INFO 2024-04-19 09:58:21,295 INFO ================================================================================ 2024-04-19 09:58:21,295 INFO 2024-04-19 09:56:03 15s elapsed 2024-04-19 09:58:21,295 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,295 INFO > Global (OK=24387 KO=0 ) 2024-04-19 09:58:21,295 INFO > userRestLogin (OK=24387 KO=0 ) 2024-04-19 09:58:21,295 INFO 2024-04-19 09:58:21,295 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,295 INFO [- ] 0% 2024-04-19 09:58:21,295 INFO waiting: 23479737 / active: 40 / done: 24386 2024-04-19 09:58:21,295 INFO ================================================================================ 2024-04-19 09:58:21,295 INFO 2024-04-19 09:58:21,295 INFO 2024-04-19 09:58:21,295 INFO ================================================================================ 2024-04-19 09:58:21,295 INFO 2024-04-19 09:56:08 20s elapsed 2024-04-19 09:58:21,295 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,295 INFO > Global (OK=43345 KO=0 ) 2024-04-19 09:58:21,295 INFO > userRestLogin (OK=43345 KO=0 ) 2024-04-19 09:58:21,295 INFO 2024-04-19 09:58:21,295 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,295 INFO [- ] 0% 2024-04-19 09:58:21,295 INFO waiting: 23460763 / active: 57 / done: 43343 2024-04-19 09:58:21,295 INFO ================================================================================ 2024-04-19 09:58:21,296 INFO 2024-04-19 09:58:21,296 INFO 2024-04-19 09:58:21,296 INFO ================================================================================ 2024-04-19 09:58:21,296 INFO 2024-04-19 09:56:13 25s elapsed 2024-04-19 09:58:21,296 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,296 INFO > Global (OK=67711 KO=0 ) 2024-04-19 09:58:21,296 INFO > userRestLogin (OK=67711 KO=0 ) 2024-04-19 09:58:21,296 INFO 2024-04-19 09:58:21,296 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,296 INFO [- ] 0% 2024-04-19 09:58:21,296 INFO waiting: 23436377 / active: 75 / done: 67711 2024-04-19 09:58:21,296 INFO ================================================================================ 2024-04-19 09:58:21,296 INFO 2024-04-19 09:58:21,296 INFO 2024-04-19 09:58:21,296 INFO ================================================================================ 2024-04-19 09:58:21,296 INFO 2024-04-19 09:56:18 30s elapsed 2024-04-19 09:58:21,296 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,296 INFO > Global (OK=97370 KO=0 ) 2024-04-19 09:58:21,296 INFO > userRestLogin (OK=97370 KO=0 ) 2024-04-19 09:58:21,296 INFO 2024-04-19 09:58:21,296 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,296 INFO [- ] 0% 2024-04-19 09:58:21,296 INFO waiting: 23406675 / active: 120 / done: 97368 2024-04-19 09:58:21,296 INFO ================================================================================ 2024-04-19 09:58:21,296 INFO 2024-04-19 09:58:21,296 INFO 2024-04-19 09:58:21,296 INFO ================================================================================ 2024-04-19 09:58:21,296 INFO 2024-04-19 09:56:23 35s elapsed 2024-04-19 09:58:21,296 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,296 INFO > Global (OK=129926 KO=0 ) 2024-04-19 09:58:21,296 INFO > userRestLogin (OK=129926 KO=0 ) 2024-04-19 09:58:21,296 INFO 2024-04-19 09:58:21,296 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,296 INFO [- ] 0% 2024-04-19 09:58:21,296 INFO waiting: 23374066 / active: 172 / done: 129925 2024-04-19 09:58:21,296 INFO ================================================================================ 2024-04-19 09:58:21,296 INFO 2024-04-19 09:58:21,296 INFO 2024-04-19 09:58:21,296 INFO ================================================================================ 2024-04-19 09:58:21,296 INFO 2024-04-19 09:56:28 40s elapsed 2024-04-19 09:58:21,296 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,297 INFO > Global (OK=162088 KO=0 ) 2024-04-19 09:58:21,297 INFO > userRestLogin (OK=162088 KO=0 ) 2024-04-19 09:58:21,297 INFO 2024-04-19 09:58:21,297 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,297 INFO [- ] 0% 2024-04-19 09:58:21,297 INFO waiting: 23341871 / active: 205 / done: 162087 2024-04-19 09:58:21,297 INFO ================================================================================ 2024-04-19 09:58:21,297 INFO 2024-04-19 09:58:21,297 INFO 2024-04-19 09:58:21,297 INFO [...] 2024-04-19 09:58:21,299 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,299 INFO > Global (OK=23340496 KO=0 ) 2024-04-19 09:58:21,299 INFO > userRestLogin (OK=23340496 KO=0 ) 2024-04-19 09:58:21,299 INFO 2024-04-19 09:58:21,299 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,299 INFO [#########################################################################-] 99% 2024-04-19 09:58:21,299 INFO waiting: 163215 / active: 454 / done: 23340494 2024-04-19 09:58:21,299 INFO ================================================================================ 2024-04-19 09:58:21,299 INFO 2024-04-19 09:58:21,300 INFO 2024-04-19 09:58:21,300 INFO ================================================================================ 2024-04-19 09:58:21,300 INFO 2024-04-19 10:55:58 3610s elapsed 2024-04-19 09:58:21,300 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,300 INFO > Global (OK=23373379 KO=0 ) 2024-04-19 09:58:21,300 INFO > userRestLogin (OK=23373379 KO=0 ) 2024-04-19 09:58:21,300 INFO 2024-04-19 09:58:21,300 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,300 INFO [#########################################################################-] 99% 2024-04-19 09:58:21,300 INFO waiting: 130599 / active: 185 / done: 23373379 2024-04-19 09:58:21,300 INFO ================================================================================ 2024-04-19 09:58:21,300 INFO 2024-04-19 09:58:21,300 INFO 2024-04-19 09:58:21,300 INFO ================================================================================ 2024-04-19 09:58:21,300 INFO 2024-04-19 10:56:03 3615s elapsed 2024-04-19 09:58:21,300 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,300 INFO > Global (OK=23406029 KO=0 ) 2024-04-19 09:58:21,300 INFO > userRestLogin (OK=23406029 KO=0 ) 2024-04-19 09:58:21,300 INFO 2024-04-19 09:58:21,300 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,300 INFO [#########################################################################-] 99% 2024-04-19 09:58:21,300 INFO waiting: 97944 / active: 191 / done: 23406028 2024-04-19 09:58:21,300 INFO ================================================================================ 2024-04-19 09:58:21,300 INFO 2024-04-19 09:58:21,300 INFO 2024-04-19 09:58:21,300 INFO ================================================================================ 2024-04-19 09:58:21,300 INFO 2024-04-19 10:56:08 3620s elapsed 2024-04-19 09:58:21,300 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,300 INFO > Global (OK=23438724 KO=0 ) 2024-04-19 09:58:21,300 INFO > userRestLogin (OK=23438724 KO=0 ) 2024-04-19 09:58:21,300 INFO 2024-04-19 09:58:21,300 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,300 INFO [#########################################################################-] 99% 2024-04-19 09:58:21,300 INFO waiting: 65204 / active: 236 / done: 23438723 2024-04-19 09:58:21,300 INFO ================================================================================ 2024-04-19 09:58:21,300 INFO 2024-04-19 09:58:21,300 INFO 2024-04-19 09:58:21,300 INFO ================================================================================ 2024-04-19 09:58:21,300 INFO 2024-04-19 10:56:13 3625s elapsed 2024-04-19 09:58:21,300 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,301 INFO > Global (OK=23471084 KO=0 ) 2024-04-19 09:58:21,301 INFO > userRestLogin (OK=23471084 KO=0 ) 2024-04-19 09:58:21,301 INFO 2024-04-19 09:58:21,301 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,301 INFO [#########################################################################-] 99% 2024-04-19 09:58:21,301 INFO waiting: 32820 / active: 260 / done: 23471083 2024-04-19 09:58:21,301 INFO ================================================================================ 2024-04-19 09:58:21,301 INFO 2024-04-19 09:58:21,301 INFO 2024-04-19 09:58:21,301 INFO ================================================================================ 2024-04-19 09:58:21,301 INFO 2024-04-19 10:56:18 3630s elapsed 2024-04-19 09:58:21,301 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,301 INFO > Global (OK=23502989 KO=0 ) 2024-04-19 09:58:21,301 INFO > userRestLogin (OK=23502989 KO=0 ) 2024-04-19 09:58:21,301 INFO 2024-04-19 09:58:21,301 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,301 INFO [#########################################################################-] 99% 2024-04-19 09:58:21,301 INFO waiting: 0 / active: 1174 / done: 23502989 2024-04-19 09:58:21,301 INFO ================================================================================ 2024-04-19 09:58:21,301 INFO 2024-04-19 09:58:21,301 INFO 2024-04-19 09:58:21,301 INFO ================================================================================ 2024-04-19 09:58:21,301 INFO 2024-04-19 10:56:18 3630s elapsed 2024-04-19 09:58:21,301 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 09:58:21,301 INFO > Global (OK=23504163 KO=0 ) 2024-04-19 09:58:21,301 INFO > userRestLogin (OK=23504163 KO=0 ) 2024-04-19 09:58:21,301 INFO 2024-04-19 09:58:21,301 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 09:58:21,301 INFO [##########################################################################]100% 2024-04-19 09:58:21,301 INFO waiting: 0 / active: 0 / done: 23504163 2024-04-19 09:58:21,301 INFO ================================================================================ 2024-04-19 09:58:21,301 INFO 2024-04-19 09:58:21,301 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-19 09:58:21,301 INFO 2024-04-19 09:58:21,301 INFO ---------------------- 2024-04-19 09:58:21,301 INFO [19/Apr/2024 10:56:18] Create tar file authn-users-6500.tar.gz 2024-04-19 09:58:21,301 INFO ---------------------- 2024-04-19 09:58:21,301 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-19 09:58:21,301 INFO RESULT_FOLDER=authn-users-6500 2024-04-19 09:58:21,301 INFO SIMULATION_PATH=authn-users-6500/loadtest-20240419085547279 2024-04-19 09:58:21,301 INFO PWD=/results/gatling/authn-users-6500/loadtest-20240419085547279 2024-04-19 09:58:21,301 INFO [cmd] tar -czvf /results/gatling//authn-users-6500.tar.gz --exclude=simulation.log * 2024-04-19 09:58:21,302 INFO /results/gatling 2024-04-19 09:58:21,302 INFO 2024-04-19 09:58:21,302 INFO ---------------------- 2024-04-19 09:58:21,302 INFO [19/Apr/2024 10:56:18] Update /results/gatling//latest 2024-04-19 09:58:21,302 INFO ---------------------- 2024-04-19 09:58:21,302 INFO 2024-04-19 09:58:21,302 INFO ---------------------- 2024-04-19 09:58:21,302 INFO [19/Apr/2024 10:56:18] Update /results/gatling//latest.tar.gz 2024-04-19 09:58:21,302 INFO ---------------------- 2024-04-19 09:58:21,302 INFO ________________________________________________________________________________ [2024-04-19 09:58:21] authn-users-6500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-19 09:58:21,302 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-6500/stderr.txt is small (0 lines), printing all lines: 2024-04-19 09:58:21,302 DEBUG ----- output ----- 2024-04-19 09:58:21,302 DEBUG 2024-04-19 09:58:21,302 INFO ________________________________________________________________________________ [2024-04-19 09:58:21] authn-users-6500 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-19 09:58:21] authn-users-6500 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-19 09:58:21] authn-users-6500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped