--Task-- name: authn-users-8000 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-7500'] preceding_task: authn-users-7500 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 8000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-19 11:41:50] authn-users-8000 pre : N/A ________________________________________________________________________________ [2024-04-19 11:41:50] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-19 11:41:50] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-8000/stdout.txt Pod log : /results/orders/authn-users-8000 2024-04-19 11:41:50,928 INFO 2024-04-19 11:41:50,928 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 11:41:50,928 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:51,232 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:51,232 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:51,232 DEBUG --- stdout --- 2024-04-19 11:41:51,232 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:51,232 DEBUG --- stderr --- 2024-04-19 11:41:51,232 DEBUG ________________________________________________________________________________ [2024-04-19 11:41:51] authn-users-8000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-19 11:41:51,233 INFO 2024-04-19 11:41:51,233 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:41:51,233 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:51,548 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:51,548 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:51,548 DEBUG --- stdout --- 2024-04-19 11:41:51,548 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:51,548 DEBUG --- stderr --- 2024-04-19 11:41:51,548 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-8000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-19 11:41:51] authn-users-8000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-19 11:41:51,549 INFO 2024-04-19 11:41:51,549 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 11:41:51,549 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:51,871 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:51,872 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:51,872 DEBUG --- stdout --- 2024-04-19 11:41:51,872 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:51,872 DEBUG --- stderr --- 2024-04-19 11:41:51,872 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/order.json { "name": "authn-users-8000", "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-8000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-8000", "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=8000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-19 11:41:51] authn-users-8000 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 11:41:51,873 INFO 2024-04-19 11:41:51,873 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-19 11:41:51,873 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 11:41:52,116 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:41:52,117 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:52,117 DEBUG --- stdout --- 2024-04-19 11:41:52,117 DEBUG WAITING 2024-04-19 11:41:52,117 DEBUG --- stderr --- 2024-04-19 11:41:52,118 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-19 11:41:52] authn-users-8000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-19 11:41:52,119 INFO 2024-04-19 11:41:52,119 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:41:52,119 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:52,486 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:52,486 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:52,486 DEBUG --- stdout --- 2024-04-19 11:41:52,486 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:52,486 DEBUG --- stderr --- 2024-04-19 11:41:52,486 DEBUG 2024-04-19 11:41:52,487 INFO 2024-04-19 11:41:52,487 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/07_authn-users-8000/order.json overseer-0-5c67bf94db-77mh9:/results/orders/order.json 2024-04-19 11:41:52,487 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:41:53,871 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:53,871 DEBUG --- stdout --- 2024-04-19 11:41:53,871 DEBUG 2024-04-19 11:41:53,871 DEBUG --- stderr --- 2024-04-19 11:41:53,871 DEBUG Order has been successfully forwarded to overseer [2024-04-19 11:41:53] - INFO: Check if Gatling simulation started successfully 2024-04-19 11:41:53,872 INFO 2024-04-19 11:41:53,872 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 11:41:53,872 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:54,172 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:54,172 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:54,172 DEBUG --- stdout --- 2024-04-19 11:41:54,172 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:54,172 DEBUG --- stderr --- 2024-04-19 11:41:54,172 DEBUG 2024-04-19 11:41:54,173 INFO 2024-04-19 11:41:54,173 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-8000/stdout.txt | grep "started..." 2024-04-19 11:41:54,173 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 11:41:54,922 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-19 11:42:00,691 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-19 11:42:06,471 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-19 11:42:06,472 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:42:06,472 DEBUG --- stdout --- 2024-04-19 11:42:06,472 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 11:42:06,472 DEBUG --- stderr --- 2024-04-19 11:42:06,472 DEBUG ________________________________________________________________________________ [2024-04-19 11:42:06] authn-users-8000 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 PASS : Order stopped by itself after 6m 01s and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-19 11:48:07] authn-users-8000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-19 11:48:07,505 INFO 2024-04-19 11:48:07,505 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 11:48:07,505 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:07,845 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:07,846 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:07,846 DEBUG --- stdout --- 2024-04-19 11:48:07,846 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:07,846 DEBUG --- stderr --- 2024-04-19 11:48:07,846 DEBUG 2024-04-19 11:48:07,846 INFO 2024-04-19 11:48:07,846 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 11:48:07,846 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:48:08,702 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:08,702 DEBUG --- stdout --- 2024-04-19 11:48:08,702 DEBUG 2024-04-19 11:48:08,702 DEBUG --- stderr --- 2024-04-19 11:48:08,703 DEBUG Order ran during 6m 14s ________________________________________________________________________________ [2024-04-19 11:48:08] authn-users-8000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-19 11:48:08] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/order.json 2024-04-19 11:48:08,703 INFO 2024-04-19 11:48:08,703 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 11:48:08,703 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:09,005 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:09,005 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:09,005 DEBUG --- stdout --- 2024-04-19 11:48:09,006 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:09,006 DEBUG --- stderr --- 2024-04-19 11:48:09,006 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:48:09,006 INFO 2024-04-19 11:48:09,006 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 11:48:09,006 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:09,331 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:09,332 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:09,333 DEBUG --- stdout --- 2024-04-19 11:48:09,333 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:09,333 DEBUG --- stderr --- 2024-04-19 11:48:09,333 DEBUG 2024-04-19 11:48:09,333 INFO 2024-04-19 11:48:09,334 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-8000/rc.txt" ]; then echo "/results/orders/authn-users-8000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-8000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-8000/rc.txt file found" 2024-04-19 11:48:09,334 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:48:10,172 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:48:10,172 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:10,172 DEBUG --- stdout --- 2024-04-19 11:48:10,173 DEBUG /results/orders/authn-users-8000/rc.txt file found 2024-04-19 11:48:10,173 DEBUG --- stderr --- 2024-04-19 11:48:10,173 DEBUG 2024-04-19 11:48:10,173 INFO 2024-04-19 11:48:10,173 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 11:48:10,173 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:10,472 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:10,472 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:10,472 DEBUG --- stdout --- 2024-04-19 11:48:10,472 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:10,473 DEBUG --- stderr --- 2024-04-19 11:48:10,473 DEBUG 2024-04-19 11:48:10,473 INFO 2024-04-19 11:48:10,473 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-8000/rc.txt 2024-04-19 11:48:11,291 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:48:11,292 DEBUG --- stdout --- 2024-04-19 11:48:11,292 DEBUG 4 /results/orders/authn-users-8000/rc.txt 2024-04-19 11:48:11,292 DEBUG --- stderr --- 2024-04-19 11:48:11,292 DEBUG 2024-04-19 11:48:11,292 INFO - Download file (size 4k) 2024-04-19 11:48:11,292 INFO 2024-04-19 11:48:11,292 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 11:48:11,292 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:11,611 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:11,611 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:11,612 DEBUG --- stdout --- 2024-04-19 11:48:11,612 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:11,612 DEBUG --- stderr --- 2024-04-19 11:48:11,612 DEBUG source : /results/orders/authn-users-8000/rc.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/rc.txt 2024-04-19 11:48:11,612 INFO 2024-04-19 11:48:11,612 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 11:48:11,612 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:11,926 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:11,927 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:11,927 DEBUG --- stdout --- 2024-04-19 11:48:11,927 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:11,927 DEBUG --- stderr --- 2024-04-19 11:48:11,927 DEBUG 2024-04-19 11:48:11,927 INFO 2024-04-19 11:48:11,927 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-8000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/rc.txt 2024-04-19 11:48:11,927 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:48:12,688 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:12,688 DEBUG --- stdout --- 2024-04-19 11:48:12,688 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:48:12,688 DEBUG --- stderr --- 2024-04-19 11:48:12,689 DEBUG 2024-04-19 11:48:12,689 INFO 2024-04-19 11:48:12,689 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 11:48:12,689 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:12,988 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:12,988 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:12,988 DEBUG --- stdout --- 2024-04-19 11:48:12,988 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:12,988 DEBUG --- stderr --- 2024-04-19 11:48:12,988 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:48:12,988 INFO 2024-04-19 11:48:12,988 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 11:48:12,988 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:13,289 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:13,289 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:13,289 DEBUG --- stdout --- 2024-04-19 11:48:13,289 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:13,289 DEBUG --- stderr --- 2024-04-19 11:48:13,289 DEBUG 2024-04-19 11:48:13,290 INFO 2024-04-19 11:48:13,290 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-8000/stderr.txt" ]; then echo "/results/orders/authn-users-8000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-8000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-8000/stderr.txt file found" 2024-04-19 11:48:13,290 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:48:14,182 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:48:14,182 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:14,183 DEBUG --- stdout --- 2024-04-19 11:48:14,183 DEBUG /results/orders/authn-users-8000/stderr.txt file found 2024-04-19 11:48:14,183 DEBUG --- stderr --- 2024-04-19 11:48:14,183 DEBUG 2024-04-19 11:48:14,183 INFO 2024-04-19 11:48:14,183 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 11:48:14,183 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:14,532 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:14,534 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:14,534 DEBUG --- stdout --- 2024-04-19 11:48:14,534 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:14,534 DEBUG --- stderr --- 2024-04-19 11:48:14,534 DEBUG 2024-04-19 11:48:14,534 INFO 2024-04-19 11:48:14,535 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-8000/stderr.txt 2024-04-19 11:48:15,367 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:48:15,367 DEBUG --- stdout --- 2024-04-19 11:48:15,368 DEBUG 0 /results/orders/authn-users-8000/stderr.txt 2024-04-19 11:48:15,368 DEBUG --- stderr --- 2024-04-19 11:48:15,368 DEBUG 2024-04-19 11:48:15,368 INFO - Download file (size 0k) 2024-04-19 11:48:15,368 INFO 2024-04-19 11:48:15,368 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 11:48:15,368 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:15,699 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:15,699 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:15,699 DEBUG --- stdout --- 2024-04-19 11:48:15,700 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:15,700 DEBUG --- stderr --- 2024-04-19 11:48:15,700 DEBUG source : /results/orders/authn-users-8000/stderr.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/stderr.txt 2024-04-19 11:48:15,700 INFO 2024-04-19 11:48:15,700 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 11:48:15,700 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:16,035 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:16,035 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:16,035 DEBUG --- stdout --- 2024-04-19 11:48:16,035 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:16,036 DEBUG --- stderr --- 2024-04-19 11:48:16,036 DEBUG 2024-04-19 11:48:16,036 INFO 2024-04-19 11:48:16,036 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-8000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/stderr.txt 2024-04-19 11:48:16,036 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:48:16,777 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:16,777 DEBUG --- stdout --- 2024-04-19 11:48:16,777 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:48:16,777 DEBUG --- stderr --- 2024-04-19 11:48:16,777 DEBUG 2024-04-19 11:48:16,777 INFO 2024-04-19 11:48:16,777 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 11:48:16,777 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:17,082 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:17,083 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:17,083 DEBUG --- stdout --- 2024-04-19 11:48:17,083 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:17,083 DEBUG --- stderr --- 2024-04-19 11:48:17,083 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:48:17,084 INFO 2024-04-19 11:48:17,084 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 11:48:17,084 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:17,390 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:17,390 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:17,390 DEBUG --- stdout --- 2024-04-19 11:48:17,390 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:17,390 DEBUG --- stderr --- 2024-04-19 11:48:17,390 DEBUG 2024-04-19 11:48:17,391 INFO 2024-04-19 11:48:17,391 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-8000/stdout.txt" ]; then echo "/results/orders/authn-users-8000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-8000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-8000/stdout.txt file found" 2024-04-19 11:48:17,391 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:48:18,215 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:48:18,216 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:18,216 DEBUG --- stdout --- 2024-04-19 11:48:18,216 DEBUG /results/orders/authn-users-8000/stdout.txt file found 2024-04-19 11:48:18,216 DEBUG --- stderr --- 2024-04-19 11:48:18,216 DEBUG 2024-04-19 11:48:18,216 INFO 2024-04-19 11:48:18,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 11:48:18,216 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:18,538 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:18,538 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:18,538 DEBUG --- stdout --- 2024-04-19 11:48:18,538 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:18,538 DEBUG --- stderr --- 2024-04-19 11:48:18,539 DEBUG 2024-04-19 11:48:18,539 INFO 2024-04-19 11:48:18,539 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-8000/stdout.txt 2024-04-19 11:48:19,372 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:48:19,373 DEBUG --- stdout --- 2024-04-19 11:48:19,373 DEBUG 1040 /results/orders/authn-users-8000/stdout.txt 2024-04-19 11:48:19,373 DEBUG --- stderr --- 2024-04-19 11:48:19,373 DEBUG 2024-04-19 11:48:19,373 INFO - Download file (size 1040k) 2024-04-19 11:48:19,374 INFO 2024-04-19 11:48:19,374 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 11:48:19,374 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:19,752 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:19,752 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:19,753 DEBUG --- stdout --- 2024-04-19 11:48:19,753 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:19,753 DEBUG --- stderr --- 2024-04-19 11:48:19,753 DEBUG source : /results/orders/authn-users-8000/stdout.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/stdout.txt 2024-04-19 11:48:19,754 INFO 2024-04-19 11:48:19,754 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 11:48:19,754 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:20,112 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:20,113 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:20,113 DEBUG --- stdout --- 2024-04-19 11:48:20,113 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:20,113 DEBUG --- stderr --- 2024-04-19 11:48:20,113 DEBUG 2024-04-19 11:48:20,113 INFO 2024-04-19 11:48:20,113 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-8000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/stdout.txt 2024-04-19 11:48:20,113 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:48:21,265 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:21,265 DEBUG --- stdout --- 2024-04-19 11:48:21,266 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:48:21,266 DEBUG --- stderr --- 2024-04-19 11:48:21,266 DEBUG ________________________________________________________________________________ [2024-04-19 11:48:21] authn-users-8000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-19 11:48:21,267 INFO 2024-04-19 11:48:21,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 11:48:21,267 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:21,611 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:21,612 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:21,612 DEBUG --- stdout --- 2024-04-19 11:48:21,612 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:21,612 DEBUG --- stderr --- 2024-04-19 11:48:21,612 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:48:21,612 INFO 2024-04-19 11:48:21,612 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 11:48:21,612 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:21,951 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:21,951 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:21,951 DEBUG --- stdout --- 2024-04-19 11:48:21,951 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:21,951 DEBUG --- stderr --- 2024-04-19 11:48:21,951 DEBUG 2024-04-19 11:48:21,952 INFO 2024-04-19 11:48:21,952 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-8000/cmd.txt" ]; then echo "/results/orders/authn-users-8000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-8000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-8000/cmd.txt file found" 2024-04-19 11:48:21,952 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:48:22,775 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:48:22,775 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:22,775 DEBUG --- stdout --- 2024-04-19 11:48:22,775 DEBUG /results/orders/authn-users-8000/cmd.txt file found 2024-04-19 11:48:22,775 DEBUG --- stderr --- 2024-04-19 11:48:22,775 DEBUG 2024-04-19 11:48:22,776 INFO 2024-04-19 11:48:22,776 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 11:48:22,776 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:23,079 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:23,079 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:23,079 DEBUG --- stdout --- 2024-04-19 11:48:23,079 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:23,079 DEBUG --- stderr --- 2024-04-19 11:48:23,079 DEBUG 2024-04-19 11:48:23,080 INFO 2024-04-19 11:48:23,080 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-8000/cmd.txt 2024-04-19 11:48:23,897 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:48:23,897 DEBUG --- stdout --- 2024-04-19 11:48:23,897 DEBUG 4 /results/orders/authn-users-8000/cmd.txt 2024-04-19 11:48:23,897 DEBUG --- stderr --- 2024-04-19 11:48:23,897 DEBUG 2024-04-19 11:48:23,897 INFO - Download file (size 4k) 2024-04-19 11:48:23,898 INFO 2024-04-19 11:48:23,898 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 11:48:23,898 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:24,222 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:24,222 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:24,222 DEBUG --- stdout --- 2024-04-19 11:48:24,222 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:24,222 DEBUG --- stderr --- 2024-04-19 11:48:24,222 DEBUG source : /results/orders/authn-users-8000/cmd.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/cmd.txt 2024-04-19 11:48:24,223 INFO 2024-04-19 11:48:24,223 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 11:48:24,223 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:48:24,537 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:48:24,538 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:24,538 DEBUG --- stdout --- 2024-04-19 11:48:24,538 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:48:24,538 DEBUG --- stderr --- 2024-04-19 11:48:24,538 DEBUG 2024-04-19 11:48:24,538 INFO 2024-04-19 11:48:24,538 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-8000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/cmd.txt 2024-04-19 11:48:24,538 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:48:25,316 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:48:25,317 DEBUG --- stdout --- 2024-04-19 11:48:25,317 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:48:25,317 DEBUG --- stderr --- 2024-04-19 11:48:25,317 DEBUG 2024-04-19 11:48:25,317 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/cmd.txt is small (1 lines), printing all lines: 2024-04-19 11:48:25,317 DEBUG ----- output ----- 2024-04-19 11:48:25,317 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-8000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-19 11:48:25,317 INFO ________________________________________________________________________________ [2024-04-19 11:48:25] authn-users-8000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-19 11:48:25,318 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/rc.txt is small (1 lines), printing all lines: 2024-04-19 11:48:25,318 DEBUG ----- output ----- 2024-04-19 11:48:25,318 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-19 11:48:25,318 INFO ________________________________________________________________________________ [2024-04-19 11:48:25] authn-users-8000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-19 11:48:25,322 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/07_authn-users-8000/stdout.txt is 16381 lines): 2024-04-19 11:48:25,322 DEBUG ----- output ----- 2024-04-19 11:48:25,323 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-19 11:48:25,323 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-19 11:48:25,323 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 11:48:25,323 INFO 2024-04-19 11:48:25,323 INFO ================================================================================ 2024-04-19 11:48:25,323 INFO 2024-04-19 12:42:07 5s elapsed 2024-04-19 11:48:25,323 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:48:25,323 INFO > Global (OK=3281 KO=0 ) 2024-04-19 11:48:25,323 INFO > userRestLogin (OK=3281 KO=0 ) 2024-04-19 11:48:25,323 INFO 2024-04-19 11:48:25,323 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:48:25,325 INFO [- ] 0% 2024-04-19 11:48:25,326 INFO waiting: 28921720 / active: 61 / done: 3281 2024-04-19 11:48:25,326 INFO ================================================================================ 2024-04-19 11:48:25,326 INFO 2024-04-19 11:48:25,326 INFO 2024-04-19 11:48:25,326 INFO ================================================================================ 2024-04-19 11:48:25,326 INFO 2024-04-19 12:42:12 10s elapsed 2024-04-19 11:48:25,326 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:48:25,326 INFO > Global (OK=13320 KO=0 ) 2024-04-19 11:48:25,326 INFO > userRestLogin (OK=13320 KO=0 ) 2024-04-19 11:48:25,326 INFO 2024-04-19 11:48:25,326 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:48:25,326 INFO [- ] 0% 2024-04-19 11:48:25,326 INFO waiting: 28911712 / active: 30 / done: 13320 2024-04-19 11:48:25,326 INFO ================================================================================ 2024-04-19 11:48:25,326 INFO 2024-04-19 11:48:25,326 INFO 2024-04-19 11:48:25,326 INFO ================================================================================ 2024-04-19 11:48:25,326 INFO 2024-04-19 12:42:17 15s elapsed 2024-04-19 11:48:25,326 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:48:25,327 INFO > Global (OK=29927 KO=0 ) 2024-04-19 11:48:25,327 INFO > userRestLogin (OK=29927 KO=0 ) 2024-04-19 11:48:25,327 INFO 2024-04-19 11:48:25,327 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:48:25,327 INFO [- ] 0% 2024-04-19 11:48:25,327 INFO waiting: 28895037 / active: 98 / done: 29927 2024-04-19 11:48:25,327 INFO ================================================================================ 2024-04-19 11:48:25,327 INFO 2024-04-19 11:48:25,327 INFO 2024-04-19 11:48:25,327 INFO ================================================================================ 2024-04-19 11:48:25,327 INFO 2024-04-19 12:42:22 20s elapsed 2024-04-19 11:48:25,327 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:48:25,327 INFO > Global (OK=53123 KO=0 ) 2024-04-19 11:48:25,327 INFO > userRestLogin (OK=53123 KO=0 ) 2024-04-19 11:48:25,327 INFO 2024-04-19 11:48:25,327 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:48:25,327 INFO [- ] 0% 2024-04-19 11:48:25,327 INFO waiting: 28871707 / active: 233 / done: 53122 2024-04-19 11:48:25,327 INFO ================================================================================ 2024-04-19 11:48:25,327 INFO 2024-04-19 11:48:25,327 INFO 2024-04-19 11:48:25,327 INFO ================================================================================ 2024-04-19 11:48:25,327 INFO 2024-04-19 12:42:27 25s elapsed 2024-04-19 11:48:25,327 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:48:25,327 INFO > Global (OK=82988 KO=0 ) 2024-04-19 11:48:25,328 INFO > userRestLogin (OK=82988 KO=0 ) 2024-04-19 11:48:25,328 INFO 2024-04-19 11:48:25,328 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:48:25,328 INFO [- ] 0% 2024-04-19 11:48:25,328 INFO waiting: 28841695 / active: 381 / done: 82986 2024-04-19 11:48:25,328 INFO ================================================================================ 2024-04-19 11:48:25,328 INFO 2024-04-19 11:48:25,328 INFO 2024-04-19 11:48:25,328 INFO ================================================================================ 2024-04-19 11:48:25,328 INFO 2024-04-19 12:42:32 30s elapsed 2024-04-19 11:48:25,328 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:48:25,328 INFO > Global (OK=118482 KO=0 ) 2024-04-19 11:48:25,328 INFO > userRestLogin (OK=118482 KO=0 ) 2024-04-19 11:48:25,328 INFO 2024-04-19 11:48:25,328 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:48:25,328 INFO [- ] 0% 2024-04-19 11:48:25,328 INFO waiting: 28805196 / active: 1385 / done: 118481 2024-04-19 11:48:25,328 INFO ================================================================================ 2024-04-19 11:48:25,328 INFO 2024-04-19 11:48:25,328 INFO 2024-04-19 11:48:25,328 INFO ================================================================================ 2024-04-19 11:48:25,328 INFO 2024-04-19 12:42:37 35s elapsed 2024-04-19 11:48:25,328 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:48:25,328 INFO > Global (OK=157084 KO=0 ) 2024-04-19 11:48:25,328 INFO > userRestLogin (OK=157084 KO=0 ) 2024-04-19 11:48:25,329 INFO 2024-04-19 11:48:25,329 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:48:25,329 INFO [- ] 0% 2024-04-19 11:48:25,329 INFO waiting: 28765662 / active: 2317 / done: 157083 2024-04-19 11:48:25,329 INFO ================================================================================ 2024-04-19 11:48:25,329 INFO 2024-04-19 11:48:25,329 INFO 2024-04-19 11:48:25,329 INFO ================================================================================ 2024-04-19 11:48:25,329 INFO 2024-04-19 12:42:42 40s elapsed 2024-04-19 11:48:25,329 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:48:25,329 INFO > Global (OK=196887 KO=0 ) 2024-04-19 11:48:25,329 INFO > userRestLogin (OK=196887 KO=0 ) 2024-04-19 11:48:25,329 INFO 2024-04-19 11:48:25,329 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:48:25,329 INFO [- ] 0% 2024-04-19 11:48:25,329 INFO waiting: 28725271 / active: 2905 / done: 196886 2024-04-19 11:48:25,329 INFO ================================================================================ 2024-04-19 11:48:25,329 INFO 2024-04-19 11:48:25,329 INFO 2024-04-19 11:48:25,329 INFO [...] 2024-04-19 11:48:25,337 INFO Session: 2024-04-19 11:48:25,337 INFO Session(am.AMAuthN,1181878,HashMap(gatling.http.cache.baseUrl -> https://xlou.iam.xlou-cdm.engineeringpit.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@5ff6a83d, username -> user.18893, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@314ce614, password -> T35tr0ck123),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$789/0x00007ff9206b8ba0@687c5caa,io.netty.channel.epoll.EpollEventLoop@159e366) 2024-04-19 11:48:25,337 INFO ========================= 2024-04-19 11:48:25,337 INFO HTTP request: 2024-04-19 11:48:25,338 INFO POST https://xlou.iam.xlou-cdm.engineeringpit.com/am/json/realms/root/authenticate 2024-04-19 11:48:25,338 INFO headers: 2024-04-19 11:48:25,338 INFO Accept-API-Version: resource=2.0, protocol=1.0 2024-04-19 11:48:25,338 INFO X-OpenAM-Username: user.18893 2024-04-19 11:48:25,338 INFO X-OpenAM-Password: T35tr0ck123 2024-04-19 11:48:25,338 INFO accept: */* 2024-04-19 11:48:25,338 INFO host: xlou.iam.xlou-cdm.engineeringpit.com 2024-04-19 11:48:25,338 INFO content-length: 0 2024-04-19 11:48:25,338 INFO ========================= 2024-04-19 11:48:25,338 INFO HTTP response: 2024-04-19 11:48:25,338 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-04-19 11:48:25,338 INFO 12:46:17.675 [ERROR] i.g.c.action.If - 'if-2' failed to execute: j.u.NoSuchElementException: No attribute named 'auth_resp_code' is defined 2024-04-19 11:48:25,338 INFO 12:46:17.675 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'userRestLogin' failed for user 1181894: Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:48:25,338 INFO 12:46:17.675 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-04-19 11:48:25,338 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-04-19 11:48:25,338 INFO Request: 2024-04-19 11:48:25,338 INFO userRestLogin: KO Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:48:25,338 INFO ========================= 2024-04-19 11:48:25,338 INFO Session: 2024-04-19 11:48:25,338 INFO Session(am.AMAuthN,1181894,HashMap(gatling.http.cache.baseUrl -> https://xlou.iam.xlou-cdm.engineeringpit.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@6ab3899f, username -> user.52679, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@26ead7fe, password -> T35tr0ck123),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$789/0x00007ff9206b8ba0@687c5caa,io.netty.channel.epoll.EpollEventLoop@159e366) 2024-04-19 11:48:25,339 INFO ========================= 2024-04-19 11:48:25,339 INFO HTTP request: 2024-04-19 11:48:25,339 INFO POST https://xlou.iam.xlou-cdm.engineeringpit.com/am/json/realms/root/authenticate 2024-04-19 11:48:25,339 INFO headers: 2024-04-19 11:48:25,339 INFO Accept-API-Version: resource=2.0, protocol=1.0 2024-04-19 11:48:25,339 INFO X-OpenAM-Username: user.52679 2024-04-19 11:48:25,339 INFO X-OpenAM-Password: T35tr0ck123 2024-04-19 11:48:25,339 INFO accept: */* 2024-04-19 11:48:25,339 INFO host: xlou.iam.xlou-cdm.engineeringpit.com 2024-04-19 11:48:25,339 INFO content-length: 0 2024-04-19 11:48:25,339 INFO ========================= 2024-04-19 11:48:25,339 INFO HTTP response: 2024-04-19 11:48:25,339 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-04-19 11:48:25,339 INFO 12:46:17.675 [ERROR] i.g.c.action.If - 'if-2' failed to execute: j.u.NoSuchElementException: No attribute named 'auth_resp_code' is defined 2024-04-19 11:48:25,339 INFO 12:46:17.676 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'userRestLogin' failed for user 1181910: Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:48:25,339 INFO 12:46:17.676 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-04-19 11:48:25,339 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-04-19 11:48:25,339 INFO Request: 2024-04-19 11:48:25,339 INFO userRestLogin: KO Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:48:25,339 INFO ========================= 2024-04-19 11:48:25,339 INFO Session: 2024-04-19 11:48:25,339 INFO Session(am.AMAuthN,1181910,HashMap(gatling.http.cache.baseUrl -> https://xlou.iam.xlou-cdm.engineeringpit.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@4c37345e, username -> user.491903, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@1a9d178a, password -> T35tr0ck123),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$789/0x00007ff9206b8ba0@687c5caa,io.netty.channel.epoll.EpollEventLoop@159e366) 2024-04-19 11:48:25,339 INFO ========================= 2024-04-19 11:48:25,340 INFO HTTP request: 2024-04-19 11:48:25,340 INFO POST https://xlou.iam.xlou-cdm.engineeringpit.com/am/json/realms/root/authenticate 2024-04-19 11:48:25,340 INFO headers: 2024-04-19 11:48:25,340 INFO Accept-API-Version: resource=2.0, protocol=1.0 2024-04-19 11:48:25,340 INFO X-OpenAM-Username: user.491903 2024-04-19 11:48:25,340 INFO X-OpenAM-Password: T35tr0ck123 2024-04-19 11:48:25,340 INFO accept: */* 2024-04-19 11:48:25,340 INFO host: xlou.iam.xlou-cdm.engineeringpit.com 2024-04-19 11:48:25,340 INFO content-length: 0 2024-04-19 11:48:25,340 INFO ========================= 2024-04-19 11:48:25,340 INFO HTTP response: 2024-04-19 11:48:25,340 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-04-19 11:48:25,340 INFO 12:46:17.676 [ERROR] i.g.c.action.If - 'if-2' failed to execute: j.u.NoSuchElementException: No attribute named 'auth_resp_code' is defined 2024-04-19 11:48:25,340 INFO 12:46:17.676 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'userRestLogin' failed for user 1181926: Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:48:25,340 INFO 12:46:17.676 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-04-19 11:48:25,340 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-04-19 11:48:25,340 INFO Request: 2024-04-19 11:48:25,340 INFO userRestLogin: KO Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:48:25,340 INFO ========================= 2024-04-19 11:48:25,340 INFO Session: 2024-04-19 11:48:25,340 INFO Session(am.AMAuthN,1181926,HashMap(gatling.http.cache.baseUrl -> https://xlou.iam.xlou-cdm.engineeringpit.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@6c949a37, username -> user.116916, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@1c31f57c, password -> T35tr0ck123),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$789/0x00007ff9206b8ba0@687c5caa,io.netty.channel.epoll.EpollEventLoop@159e366) 2024-04-19 11:48:25,340 INFO ========================= 2024-04-19 11:48:25,340 INFO HTTP request: 2024-04-19 11:48:25,340 INFO POST https://xlou.iam.xlou-cdm.engineeringpit.com/am/json/realms/root/authenticate 2024-04-19 11:48:25,340 INFO headers: 2024-04-19 11:48:25,341 INFO Accept-API-Version: resource=2.0, protocol=1.0 2024-04-19 11:48:25,341 INFO X-OpenAM-Username: user.116916 2024-04-19 11:48:25,341 INFO X-OpenAM-Password: T35tr0ck123 2024-04-19 11:48:25,341 INFO accept: */* 2024-04-19 11:48:25,341 INFO host: xlou.iam.xlou-cdm.engineeringpit.com 2024-04-19 11:48:25,341 INFO content-length: 0 2024-04-19 11:48:25,341 INFO ========================= 2024-04-19 11:48:25,341 INFO HTTP response: 2024-04-19 11:48:25,341 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-04-19 11:48:25,341 INFO 12:46:17.676 [ERROR] i.g.c.action.If - 'if-2' failed to execute: j.u.NoSuchElementException: No attribute named 'auth_resp_code' is defined 2024-04-19 11:48:25,341 INFO 2024-04-19 11:48:25,341 INFO ---------------------- 2024-04-19 11:48:25,341 INFO [19/Apr/2024 12:46:23] Create tar file authn-users-8000.tar.gz 2024-04-19 11:48:25,341 INFO ---------------------- 2024-04-19 11:48:25,341 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-19 11:48:25,341 INFO RESULT_FOLDER=authn-users-8000 2024-04-19 11:48:25,341 INFO SIMULATION_PATH=authn-users-8000/loadtest-20240419114201917 2024-04-19 11:48:25,341 INFO PWD=/results/gatling/authn-users-8000/loadtest-20240419114201917 2024-04-19 11:48:25,342 INFO [cmd] tar -czvf /results/gatling//authn-users-8000.tar.gz --exclude=simulation.log * 2024-04-19 11:48:25,342 INFO /results/gatling 2024-04-19 11:48:25,342 INFO 2024-04-19 11:48:25,342 INFO ---------------------- 2024-04-19 11:48:25,342 INFO [19/Apr/2024 12:46:23] Update /results/gatling//latest 2024-04-19 11:48:25,342 INFO ---------------------- 2024-04-19 11:48:25,342 INFO 2024-04-19 11:48:25,342 INFO ---------------------- 2024-04-19 11:48:25,342 INFO [19/Apr/2024 12:46:23] Update /results/gatling//latest.tar.gz 2024-04-19 11:48:25,342 INFO ---------------------- 2024-04-19 11:48:25,342 INFO ________________________________________________________________________________ [2024-04-19 11:48:25] authn-users-8000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-19 11:48:25,343 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-8000/stderr.txt is small (0 lines), printing all lines: 2024-04-19 11:48:25,343 DEBUG ----- output ----- 2024-04-19 11:48:25,343 DEBUG 2024-04-19 11:48:25,343 INFO ________________________________________________________________________________ [2024-04-19 11:48:25] authn-users-8000 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 11:48:25] authn-users-8000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-19 11:48:25] authn-users-8000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped