--Task-- name: authn-users-6000 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': 6000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-19 07:52:52] authn-users-6000 pre : N/A ________________________________________________________________________________ [2024-04-19 07:52:52] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-19 07:52:52] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-6000/stdout.txt Pod log : /results/orders/authn-users-6000 2024-04-19 07:52:52,523 INFO 2024-04-19 07:52:52,524 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 07:52:52,524 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:52,828 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:52,828 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:52,828 DEBUG --- stdout --- 2024-04-19 07:52:52,829 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:52,829 DEBUG --- stderr --- 2024-04-19 07:52:52,829 DEBUG ________________________________________________________________________________ [2024-04-19 07:52:52] authn-users-6000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-19 07:52:52,829 INFO 2024-04-19 07:52:52,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 07:52:52,829 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:53,138 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:53,138 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:53,138 DEBUG --- stdout --- 2024-04-19 07:52:53,138 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:53,138 DEBUG --- stderr --- 2024-04-19 07:52:53,138 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-6000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-19 07:52:53] authn-users-6000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-19 07:52:53,139 INFO 2024-04-19 07:52:53,139 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 07:52:53,139 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:53,458 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:53,459 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:53,459 DEBUG --- stdout --- 2024-04-19 07:52:53,459 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:53,459 DEBUG --- stderr --- 2024-04-19 07:52:53,459 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/order.json { "name": "authn-users-6000", "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-6000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-6000", "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=6000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-19 07:52:53] authn-users-6000 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 07:52:53,460 INFO 2024-04-19 07:52:53,460 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-19 07:52:53,460 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 07:52:53,773 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 07:52:53,773 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:53,773 DEBUG --- stdout --- 2024-04-19 07:52:53,773 DEBUG WAITING 2024-04-19 07:52:53,773 DEBUG --- stderr --- 2024-04-19 07:52:53,773 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 24 0 --:--:-- --:--:-- --:--:-- 24 ________________________________________________________________________________ [2024-04-19 07:52:53] authn-users-6000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-19 07:52:53,774 INFO 2024-04-19 07:52:53,774 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 07:52:53,774 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:54,094 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:54,094 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:54,094 DEBUG --- stdout --- 2024-04-19 07:52:54,095 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:54,095 DEBUG --- stderr --- 2024-04-19 07:52:54,095 DEBUG 2024-04-19 07:52:54,095 INFO 2024-04-19 07:52:54,095 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/order.json overseer-0-5c67bf94db-77mh9:/results/orders/order.json 2024-04-19 07:52:54,095 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 07:52:55,474 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:55,475 DEBUG --- stdout --- 2024-04-19 07:52:55,475 DEBUG 2024-04-19 07:52:55,475 DEBUG --- stderr --- 2024-04-19 07:52:55,475 DEBUG Order has been successfully forwarded to overseer [2024-04-19 07:52:55] - INFO: Check if Gatling simulation started successfully 2024-04-19 07:52:55,475 INFO 2024-04-19 07:52:55,475 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 07:52:55,475 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:55,783 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:55,783 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:55,783 DEBUG --- stdout --- 2024-04-19 07:52:55,783 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:55,783 DEBUG --- stderr --- 2024-04-19 07:52:55,783 DEBUG 2024-04-19 07:52:55,783 INFO 2024-04-19 07:52:55,783 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-6000/stdout.txt | grep "started..." 2024-04-19 07:52:55,783 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 07:52:56,540 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-19 07:53:02,338 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-19 07:53:08,103 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-19 07:53:08,103 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:53:08,103 DEBUG --- stdout --- 2024-04-19 07:53:08,103 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 07:53:08,103 DEBUG --- stderr --- 2024-04-19 07:53:08,103 DEBUG ________________________________________________________________________________ [2024-04-19 07:53:08] authn-users-6000 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 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 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 08:55:18] authn-users-6000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-19 08:55:18,821 INFO 2024-04-19 08:55:18,821 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:18,821 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:19,155 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:19,155 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:19,155 DEBUG --- stdout --- 2024-04-19 08:55:19,155 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:19,155 DEBUG --- stderr --- 2024-04-19 08:55:19,155 DEBUG 2024-04-19 08:55:19,156 INFO 2024-04-19 08:55:19,156 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 08:55:19,156 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 08:55:19,957 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:19,957 DEBUG --- stdout --- 2024-04-19 08:55:19,957 DEBUG 2024-04-19 08:55:19,957 DEBUG --- stderr --- 2024-04-19 08:55:19,957 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-19 08:55:19] authn-users-6000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-19 08:55:19] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/order.json 2024-04-19 08:55:19,958 INFO 2024-04-19 08:55:19,958 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:19,958 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:20,262 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:20,263 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:20,263 DEBUG --- stdout --- 2024-04-19 08:55:20,263 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:20,263 DEBUG --- stderr --- 2024-04-19 08:55:20,263 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 08:55:20,263 INFO 2024-04-19 08:55:20,263 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:20,263 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:20,566 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:20,566 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:20,566 DEBUG --- stdout --- 2024-04-19 08:55:20,566 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:20,566 DEBUG --- stderr --- 2024-04-19 08:55:20,566 DEBUG 2024-04-19 08:55:20,567 INFO 2024-04-19 08:55:20,567 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-6000/rc.txt" ]; then echo "/results/orders/authn-users-6000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/rc.txt file found" 2024-04-19 08:55:20,567 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 08:55:21,402 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 08:55:21,402 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:21,402 DEBUG --- stdout --- 2024-04-19 08:55:21,402 DEBUG /results/orders/authn-users-6000/rc.txt file found 2024-04-19 08:55:21,402 DEBUG --- stderr --- 2024-04-19 08:55:21,402 DEBUG 2024-04-19 08:55:21,403 INFO 2024-04-19 08:55:21,403 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:21,403 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:21,723 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:21,723 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:21,723 DEBUG --- stdout --- 2024-04-19 08:55:21,723 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:21,723 DEBUG --- stderr --- 2024-04-19 08:55:21,723 DEBUG 2024-04-19 08:55:21,724 INFO 2024-04-19 08:55:21,724 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-6000/rc.txt 2024-04-19 08:55:22,571 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 08:55:22,571 DEBUG --- stdout --- 2024-04-19 08:55:22,571 DEBUG 4 /results/orders/authn-users-6000/rc.txt 2024-04-19 08:55:22,571 DEBUG --- stderr --- 2024-04-19 08:55:22,571 DEBUG 2024-04-19 08:55:22,571 INFO - Download file (size 4k) 2024-04-19 08:55:22,572 INFO 2024-04-19 08:55:22,572 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:22,572 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:22,873 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:22,873 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:22,873 DEBUG --- stdout --- 2024-04-19 08:55:22,873 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:22,873 DEBUG --- stderr --- 2024-04-19 08:55:22,873 DEBUG source : /results/orders/authn-users-6000/rc.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/rc.txt 2024-04-19 08:55:22,874 INFO 2024-04-19 08:55:22,874 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:22,874 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:23,176 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:23,176 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:23,176 DEBUG --- stdout --- 2024-04-19 08:55:23,176 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:23,177 DEBUG --- stderr --- 2024-04-19 08:55:23,177 DEBUG 2024-04-19 08:55:23,177 INFO 2024-04-19 08:55:23,177 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-6000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/rc.txt 2024-04-19 08:55:23,177 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 08:55:23,943 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:23,944 DEBUG --- stdout --- 2024-04-19 08:55:23,944 DEBUG tar: Removing leading `/' from member names 2024-04-19 08:55:23,944 DEBUG --- stderr --- 2024-04-19 08:55:23,944 DEBUG 2024-04-19 08:55:23,945 INFO 2024-04-19 08:55:23,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-19 08:55:23,945 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:24,266 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:24,266 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:24,266 DEBUG --- stdout --- 2024-04-19 08:55:24,266 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:24,266 DEBUG --- stderr --- 2024-04-19 08:55:24,266 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 08:55:24,267 INFO 2024-04-19 08:55:24,267 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:24,267 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:24,596 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:24,596 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:24,596 DEBUG --- stdout --- 2024-04-19 08:55:24,596 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:24,596 DEBUG --- stderr --- 2024-04-19 08:55:24,596 DEBUG 2024-04-19 08:55:24,596 INFO 2024-04-19 08:55:24,597 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-6000/stderr.txt" ]; then echo "/results/orders/authn-users-6000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/stderr.txt file found" 2024-04-19 08:55:24,597 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 08:55:25,427 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 08:55:25,427 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:25,427 DEBUG --- stdout --- 2024-04-19 08:55:25,427 DEBUG /results/orders/authn-users-6000/stderr.txt file found 2024-04-19 08:55:25,427 DEBUG --- stderr --- 2024-04-19 08:55:25,427 DEBUG 2024-04-19 08:55:25,428 INFO 2024-04-19 08:55:25,428 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 08:55:25,428 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:25,741 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:25,741 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:25,741 DEBUG --- stdout --- 2024-04-19 08:55:25,741 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:25,741 DEBUG --- stderr --- 2024-04-19 08:55:25,741 DEBUG 2024-04-19 08:55:25,742 INFO 2024-04-19 08:55:25,742 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-6000/stderr.txt 2024-04-19 08:55:26,569 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 08:55:26,569 DEBUG --- stdout --- 2024-04-19 08:55:26,569 DEBUG 0 /results/orders/authn-users-6000/stderr.txt 2024-04-19 08:55:26,569 DEBUG --- stderr --- 2024-04-19 08:55:26,569 DEBUG 2024-04-19 08:55:26,569 INFO - Download file (size 0k) 2024-04-19 08:55:26,570 INFO 2024-04-19 08:55:26,570 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:26,570 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:26,875 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:26,875 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:26,875 DEBUG --- stdout --- 2024-04-19 08:55:26,875 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:26,875 DEBUG --- stderr --- 2024-04-19 08:55:26,875 DEBUG source : /results/orders/authn-users-6000/stderr.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stderr.txt 2024-04-19 08:55:26,876 INFO 2024-04-19 08:55:26,876 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:26,876 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:27,176 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:27,176 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:27,176 DEBUG --- stdout --- 2024-04-19 08:55:27,176 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:27,176 DEBUG --- stderr --- 2024-04-19 08:55:27,176 DEBUG 2024-04-19 08:55:27,177 INFO 2024-04-19 08:55:27,177 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-6000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stderr.txt 2024-04-19 08:55:27,177 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 08:55:27,924 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:27,925 DEBUG --- stdout --- 2024-04-19 08:55:27,925 DEBUG tar: Removing leading `/' from member names 2024-04-19 08:55:27,925 DEBUG --- stderr --- 2024-04-19 08:55:27,925 DEBUG 2024-04-19 08:55:27,925 INFO 2024-04-19 08:55:27,925 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:27,925 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:28,243 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:28,243 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:28,243 DEBUG --- stdout --- 2024-04-19 08:55:28,244 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:28,244 DEBUG --- stderr --- 2024-04-19 08:55:28,244 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 08:55:28,244 INFO 2024-04-19 08:55:28,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 08:55:28,244 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:28,544 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:28,544 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:28,544 DEBUG --- stdout --- 2024-04-19 08:55:28,545 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:28,545 DEBUG --- stderr --- 2024-04-19 08:55:28,545 DEBUG 2024-04-19 08:55:28,545 INFO 2024-04-19 08:55:28,545 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-6000/stdout.txt" ]; then echo "/results/orders/authn-users-6000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/stdout.txt file found" 2024-04-19 08:55:28,546 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 08:55:29,424 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 08:55:29,426 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:29,426 DEBUG --- stdout --- 2024-04-19 08:55:29,426 DEBUG /results/orders/authn-users-6000/stdout.txt file found 2024-04-19 08:55:29,426 DEBUG --- stderr --- 2024-04-19 08:55:29,426 DEBUG 2024-04-19 08:55:29,426 INFO 2024-04-19 08:55:29,427 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:29,427 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:29,732 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:29,732 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:29,732 DEBUG --- stdout --- 2024-04-19 08:55:29,732 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:29,732 DEBUG --- stderr --- 2024-04-19 08:55:29,732 DEBUG 2024-04-19 08:55:29,732 INFO 2024-04-19 08:55:29,733 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-6000/stdout.txt 2024-04-19 08:55:30,556 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 08:55:30,556 DEBUG --- stdout --- 2024-04-19 08:55:30,556 DEBUG 508 /results/orders/authn-users-6000/stdout.txt 2024-04-19 08:55:30,556 DEBUG --- stderr --- 2024-04-19 08:55:30,556 DEBUG 2024-04-19 08:55:30,556 INFO - Download file (size 508k) 2024-04-19 08:55:30,556 INFO 2024-04-19 08:55:30,556 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 08:55:30,556 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:30,859 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:30,859 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:30,859 DEBUG --- stdout --- 2024-04-19 08:55:30,859 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:30,859 DEBUG --- stderr --- 2024-04-19 08:55:30,859 DEBUG source : /results/orders/authn-users-6000/stdout.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stdout.txt 2024-04-19 08:55:30,860 INFO 2024-04-19 08:55:30,860 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:30,860 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:31,158 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:31,159 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:31,159 DEBUG --- stdout --- 2024-04-19 08:55:31,159 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:31,159 DEBUG --- stderr --- 2024-04-19 08:55:31,159 DEBUG 2024-04-19 08:55:31,160 INFO 2024-04-19 08:55:31,160 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-6000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stdout.txt 2024-04-19 08:55:31,160 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 08:55:32,234 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:32,234 DEBUG --- stdout --- 2024-04-19 08:55:32,235 DEBUG tar: Removing leading `/' from member names 2024-04-19 08:55:32,235 DEBUG --- stderr --- 2024-04-19 08:55:32,235 DEBUG ________________________________________________________________________________ [2024-04-19 08:55:32] authn-users-6000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-19 08:55:32,235 INFO 2024-04-19 08:55:32,235 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:32,235 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:32,537 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:32,538 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:32,538 DEBUG --- stdout --- 2024-04-19 08:55:32,538 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:32,538 DEBUG --- stderr --- 2024-04-19 08:55:32,538 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 08:55:32,538 INFO 2024-04-19 08:55:32,538 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:32,538 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:32,838 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:32,839 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:32,839 DEBUG --- stdout --- 2024-04-19 08:55:32,839 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:32,839 DEBUG --- stderr --- 2024-04-19 08:55:32,839 DEBUG 2024-04-19 08:55:32,839 INFO 2024-04-19 08:55:32,839 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-6000/cmd.txt" ]; then echo "/results/orders/authn-users-6000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/cmd.txt file found" 2024-04-19 08:55:32,839 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 08:55:33,657 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 08:55:33,657 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:33,657 DEBUG --- stdout --- 2024-04-19 08:55:33,657 DEBUG /results/orders/authn-users-6000/cmd.txt file found 2024-04-19 08:55:33,657 DEBUG --- stderr --- 2024-04-19 08:55:33,657 DEBUG 2024-04-19 08:55:33,657 INFO 2024-04-19 08:55:33,658 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:33,658 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:33,982 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:33,983 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:33,983 DEBUG --- stdout --- 2024-04-19 08:55:33,983 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:33,983 DEBUG --- stderr --- 2024-04-19 08:55:33,983 DEBUG 2024-04-19 08:55:33,983 INFO 2024-04-19 08:55:33,984 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-6000/cmd.txt 2024-04-19 08:55:34,826 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 08:55:34,826 DEBUG --- stdout --- 2024-04-19 08:55:34,826 DEBUG 4 /results/orders/authn-users-6000/cmd.txt 2024-04-19 08:55:34,826 DEBUG --- stderr --- 2024-04-19 08:55:34,826 DEBUG 2024-04-19 08:55:34,826 INFO - Download file (size 4k) 2024-04-19 08:55:34,826 INFO 2024-04-19 08:55:34,827 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:34,827 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:35,137 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:35,137 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:35,137 DEBUG --- stdout --- 2024-04-19 08:55:35,137 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:35,137 DEBUG --- stderr --- 2024-04-19 08:55:35,137 DEBUG source : /results/orders/authn-users-6000/cmd.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/cmd.txt 2024-04-19 08:55:35,138 INFO 2024-04-19 08:55:35,138 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:35,138 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 08:55:35,444 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 08:55:35,444 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:35,444 DEBUG --- stdout --- 2024-04-19 08:55:35,445 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 08:55:35,445 DEBUG --- stderr --- 2024-04-19 08:55:35,445 DEBUG 2024-04-19 08:55:35,445 INFO 2024-04-19 08:55:35,445 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-6000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/cmd.txt 2024-04-19 08:55:35,445 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 08:55:36,234 INFO [loop_until]: OK (rc = 0) 2024-04-19 08:55:36,234 DEBUG --- stdout --- 2024-04-19 08:55:36,234 DEBUG tar: Removing leading `/' from member names 2024-04-19 08:55:36,234 DEBUG --- stderr --- 2024-04-19 08:55:36,234 DEBUG 2024-04-19 08:55:36,234 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/cmd.txt is small (1 lines), printing all lines: 2024-04-19 08:55:36,234 DEBUG ----- output ----- 2024-04-19 08:55:36,234 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-6000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-19 08:55:36,234 INFO ________________________________________________________________________________ [2024-04-19 08:55:36] authn-users-6000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-19 08:55:36,235 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/rc.txt is small (1 lines), printing all lines: 2024-04-19 08:55:36,235 DEBUG ----- output ----- 2024-04-19 08:55:36,235 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-19 08:55:36,235 INFO ________________________________________________________________________________ [2024-04-19 08:55:36] authn-users-6000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-19 08:55:36,236 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stdout.txt is 8746 lines): 2024-04-19 08:55:36,236 DEBUG ----- output ----- 2024-04-19 08:55:36,236 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-19 08:55:36,236 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-19 08:55:36,236 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 08:55:36,237 INFO 2024-04-19 08:55:36,237 INFO ================================================================================ 2024-04-19 08:55:36,237 INFO 2024-04-19 08:53:08 5s elapsed 2024-04-19 08:55:36,237 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,237 INFO > Global (OK=2497 KO=0 ) 2024-04-19 08:55:36,237 INFO > userRestLogin (OK=2497 KO=0 ) 2024-04-19 08:55:36,237 INFO 2024-04-19 08:55:36,237 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,237 INFO [- ] 0% 2024-04-19 08:55:36,237 INFO waiting: 21691275 / active: 10 / done: 2497 2024-04-19 08:55:36,237 INFO ================================================================================ 2024-04-19 08:55:36,237 INFO 2024-04-19 08:55:36,237 INFO 2024-04-19 08:55:36,237 INFO ================================================================================ 2024-04-19 08:55:36,237 INFO 2024-04-19 08:53:13 10s elapsed 2024-04-19 08:55:36,237 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,237 INFO > Global (OK=9994 KO=0 ) 2024-04-19 08:55:36,237 INFO > userRestLogin (OK=9994 KO=0 ) 2024-04-19 08:55:36,237 INFO 2024-04-19 08:55:36,237 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,237 INFO [- ] 0% 2024-04-19 08:55:36,237 INFO waiting: 21683769 / active: 20 / done: 9993 2024-04-19 08:55:36,237 INFO ================================================================================ 2024-04-19 08:55:36,237 INFO 2024-04-19 08:55:36,237 INFO 2024-04-19 08:55:36,237 INFO ================================================================================ 2024-04-19 08:55:36,237 INFO 2024-04-19 08:53:18 15s elapsed 2024-04-19 08:55:36,237 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,237 INFO > Global (OK=22439 KO=0 ) 2024-04-19 08:55:36,237 INFO > userRestLogin (OK=22439 KO=0 ) 2024-04-19 08:55:36,237 INFO 2024-04-19 08:55:36,237 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,237 INFO [- ] 0% 2024-04-19 08:55:36,237 INFO waiting: 21671263 / active: 81 / done: 22438 2024-04-19 08:55:36,237 INFO ================================================================================ 2024-04-19 08:55:36,237 INFO 2024-04-19 08:55:36,237 INFO 2024-04-19 08:55:36,237 INFO ================================================================================ 2024-04-19 08:55:36,237 INFO 2024-04-19 08:53:23 20s elapsed 2024-04-19 08:55:36,237 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,238 INFO > Global (OK=39961 KO=0 ) 2024-04-19 08:55:36,238 INFO > userRestLogin (OK=39961 KO=0 ) 2024-04-19 08:55:36,238 INFO 2024-04-19 08:55:36,238 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,238 INFO [- ] 0% 2024-04-19 08:55:36,238 INFO waiting: 21653761 / active: 61 / done: 39960 2024-04-19 08:55:36,238 INFO ================================================================================ 2024-04-19 08:55:36,238 INFO 2024-04-19 08:55:36,238 INFO 2024-04-19 08:55:36,238 INFO ================================================================================ 2024-04-19 08:55:36,238 INFO 2024-04-19 08:53:28 25s elapsed 2024-04-19 08:55:36,238 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,238 INFO > Global (OK=62449 KO=0 ) 2024-04-19 08:55:36,238 INFO > userRestLogin (OK=62449 KO=0 ) 2024-04-19 08:55:36,238 INFO 2024-04-19 08:55:36,238 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,238 INFO [- ] 0% 2024-04-19 08:55:36,238 INFO waiting: 21631262 / active: 72 / done: 62448 2024-04-19 08:55:36,238 INFO ================================================================================ 2024-04-19 08:55:36,238 INFO 2024-04-19 08:55:36,238 INFO 2024-04-19 08:55:36,238 INFO ================================================================================ 2024-04-19 08:55:36,238 INFO 2024-04-19 08:53:33 30s elapsed 2024-04-19 08:55:36,238 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,238 INFO > Global (OK=89836 KO=0 ) 2024-04-19 08:55:36,238 INFO > userRestLogin (OK=89836 KO=0 ) 2024-04-19 08:55:36,238 INFO 2024-04-19 08:55:36,238 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,238 INFO [- ] 0% 2024-04-19 08:55:36,238 INFO waiting: 21603872 / active: 74 / done: 89836 2024-04-19 08:55:36,238 INFO ================================================================================ 2024-04-19 08:55:36,238 INFO 2024-04-19 08:55:36,238 INFO 2024-04-19 08:55:36,238 INFO ================================================================================ 2024-04-19 08:55:36,238 INFO 2024-04-19 08:53:38 35s elapsed 2024-04-19 08:55:36,238 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,238 INFO > Global (OK=119997 KO=0 ) 2024-04-19 08:55:36,238 INFO > userRestLogin (OK=119997 KO=0 ) 2024-04-19 08:55:36,239 INFO 2024-04-19 08:55:36,239 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,239 INFO [- ] 0% 2024-04-19 08:55:36,239 INFO waiting: 21573675 / active: 111 / done: 119996 2024-04-19 08:55:36,239 INFO ================================================================================ 2024-04-19 08:55:36,239 INFO 2024-04-19 08:55:36,239 INFO 2024-04-19 08:55:36,239 INFO ================================================================================ 2024-04-19 08:55:36,239 INFO 2024-04-19 08:53:43 40s elapsed 2024-04-19 08:55:36,239 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,239 INFO > Global (OK=149800 KO=0 ) 2024-04-19 08:55:36,239 INFO > userRestLogin (OK=149800 KO=0 ) 2024-04-19 08:55:36,239 INFO 2024-04-19 08:55:36,239 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,239 INFO [- ] 0% 2024-04-19 08:55:36,239 INFO waiting: 21543899 / active: 83 / done: 149800 2024-04-19 08:55:36,239 INFO ================================================================================ 2024-04-19 08:55:36,239 INFO 2024-04-19 08:55:36,239 INFO 2024-04-19 08:55:36,239 INFO [...] 2024-04-19 08:55:36,241 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,241 INFO > Global (OK=21543818 KO=0 ) 2024-04-19 08:55:36,241 INFO > userRestLogin (OK=21543818 KO=0 ) 2024-04-19 08:55:36,241 INFO 2024-04-19 08:55:36,241 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,241 INFO [#########################################################################-] 99% 2024-04-19 08:55:36,241 INFO waiting: 149886 / active: 78 / done: 21543818 2024-04-19 08:55:36,241 INFO ================================================================================ 2024-04-19 08:55:36,241 INFO 2024-04-19 08:55:36,241 INFO 2024-04-19 08:55:36,242 INFO ================================================================================ 2024-04-19 08:55:36,242 INFO 2024-04-19 09:53:13 3610s elapsed 2024-04-19 08:55:36,242 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,242 INFO > Global (OK=21573903 KO=0 ) 2024-04-19 08:55:36,242 INFO > userRestLogin (OK=21573903 KO=0 ) 2024-04-19 08:55:36,242 INFO 2024-04-19 08:55:36,242 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,242 INFO [#########################################################################-] 99% 2024-04-19 08:55:36,242 INFO waiting: 119767 / active: 112 / done: 21573903 2024-04-19 08:55:36,242 INFO ================================================================================ 2024-04-19 08:55:36,242 INFO 2024-04-19 08:55:36,242 INFO 2024-04-19 08:55:36,242 INFO ================================================================================ 2024-04-19 08:55:36,242 INFO 2024-04-19 09:53:18 3615s elapsed 2024-04-19 08:55:36,242 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,242 INFO > Global (OK=21604206 KO=0 ) 2024-04-19 08:55:36,242 INFO > userRestLogin (OK=21604206 KO=0 ) 2024-04-19 08:55:36,242 INFO 2024-04-19 08:55:36,242 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,242 INFO [#########################################################################-] 99% 2024-04-19 08:55:36,242 INFO waiting: 89489 / active: 87 / done: 21604206 2024-04-19 08:55:36,242 INFO ================================================================================ 2024-04-19 08:55:36,242 INFO 2024-04-19 08:55:36,242 INFO 2024-04-19 08:55:36,242 INFO ================================================================================ 2024-04-19 08:55:36,242 INFO 2024-04-19 09:53:23 3620s elapsed 2024-04-19 08:55:36,242 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,242 INFO > Global (OK=21634235 KO=0 ) 2024-04-19 08:55:36,242 INFO > userRestLogin (OK=21634235 KO=0 ) 2024-04-19 08:55:36,242 INFO 2024-04-19 08:55:36,242 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,242 INFO [#########################################################################-] 99% 2024-04-19 08:55:36,242 INFO waiting: 59444 / active: 104 / done: 21634234 2024-04-19 08:55:36,242 INFO ================================================================================ 2024-04-19 08:55:36,242 INFO 2024-04-19 08:55:36,242 INFO 2024-04-19 08:55:36,243 INFO ================================================================================ 2024-04-19 08:55:36,243 INFO 2024-04-19 09:53:28 3625s elapsed 2024-04-19 08:55:36,243 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,243 INFO > Global (OK=21664053 KO=0 ) 2024-04-19 08:55:36,243 INFO > userRestLogin (OK=21664053 KO=0 ) 2024-04-19 08:55:36,243 INFO 2024-04-19 08:55:36,243 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,243 INFO [#########################################################################-] 99% 2024-04-19 08:55:36,243 INFO waiting: 29618 / active: 111 / done: 21664053 2024-04-19 08:55:36,243 INFO ================================================================================ 2024-04-19 08:55:36,243 INFO 2024-04-19 08:55:36,243 INFO 2024-04-19 08:55:36,243 INFO ================================================================================ 2024-04-19 08:55:36,243 INFO 2024-04-19 09:53:33 3630s elapsed 2024-04-19 08:55:36,243 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,243 INFO > Global (OK=21693717 KO=0 ) 2024-04-19 08:55:36,243 INFO > userRestLogin (OK=21693717 KO=0 ) 2024-04-19 08:55:36,243 INFO 2024-04-19 08:55:36,243 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,243 INFO [#########################################################################-] 99% 2024-04-19 08:55:36,243 INFO waiting: 0 / active: 65 / done: 21693717 2024-04-19 08:55:36,243 INFO ================================================================================ 2024-04-19 08:55:36,243 INFO 2024-04-19 08:55:36,243 INFO 2024-04-19 08:55:36,243 INFO ================================================================================ 2024-04-19 08:55:36,243 INFO 2024-04-19 09:53:33 3630s elapsed 2024-04-19 08:55:36,243 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 08:55:36,243 INFO > Global (OK=21693782 KO=0 ) 2024-04-19 08:55:36,243 INFO > userRestLogin (OK=21693782 KO=0 ) 2024-04-19 08:55:36,243 INFO 2024-04-19 08:55:36,243 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 08:55:36,243 INFO [##########################################################################]100% 2024-04-19 08:55:36,243 INFO waiting: 0 / active: 0 / done: 21693782 2024-04-19 08:55:36,243 INFO ================================================================================ 2024-04-19 08:55:36,243 INFO 2024-04-19 08:55:36,243 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-19 08:55:36,243 INFO 2024-04-19 08:55:36,243 INFO ---------------------- 2024-04-19 08:55:36,243 INFO [19/Apr/2024 09:53:33] Create tar file authn-users-6000.tar.gz 2024-04-19 08:55:36,243 INFO ---------------------- 2024-04-19 08:55:36,243 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-19 08:55:36,243 INFO RESULT_FOLDER=authn-users-6000 2024-04-19 08:55:36,244 INFO SIMULATION_PATH=authn-users-6000/loadtest-20240419075303073 2024-04-19 08:55:36,244 INFO PWD=/results/gatling/authn-users-6000/loadtest-20240419075303073 2024-04-19 08:55:36,244 INFO [cmd] tar -czvf /results/gatling//authn-users-6000.tar.gz --exclude=simulation.log * 2024-04-19 08:55:36,244 INFO /results/gatling 2024-04-19 08:55:36,244 INFO 2024-04-19 08:55:36,244 INFO ---------------------- 2024-04-19 08:55:36,244 INFO [19/Apr/2024 09:53:33] Update /results/gatling//latest 2024-04-19 08:55:36,244 INFO ---------------------- 2024-04-19 08:55:36,244 INFO 2024-04-19 08:55:36,244 INFO ---------------------- 2024-04-19 08:55:36,244 INFO [19/Apr/2024 09:53:33] Update /results/gatling//latest.tar.gz 2024-04-19 08:55:36,244 INFO ---------------------- 2024-04-19 08:55:36,244 INFO ________________________________________________________________________________ [2024-04-19 08:55:36] authn-users-6000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-19 08:55:36,244 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stderr.txt is small (0 lines), printing all lines: 2024-04-19 08:55:36,244 DEBUG ----- output ----- 2024-04-19 08:55:36,244 DEBUG 2024-04-19 08:55:36,244 INFO ________________________________________________________________________________ [2024-04-19 08:55:36] authn-users-6000 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 08:55:36] authn-users-6000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-19 08:55:36] authn-users-6000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped