--Task-- name: authn-users-7500 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-7000'] preceding_task: authn-users-7000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 7500, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-19 11:01:07] authn-users-7500 pre : N/A ________________________________________________________________________________ [2024-04-19 11:01:07] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-19 11:01:07] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-7500/stdout.txt Pod log : /results/orders/authn-users-7500 2024-04-19 11:01:07,370 INFO 2024-04-19 11:01:07,370 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:01:07,370 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:07,699 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:07,699 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:07,699 DEBUG --- stdout --- 2024-04-19 11:01:07,699 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:07,699 DEBUG --- stderr --- 2024-04-19 11:01:07,699 DEBUG ________________________________________________________________________________ [2024-04-19 11:01:07] authn-users-7500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-19 11:01:07,700 INFO 2024-04-19 11:01:07,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:01:07,700 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:08,028 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:08,028 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:08,028 DEBUG --- stdout --- 2024-04-19 11:01:08,029 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:08,029 DEBUG --- stderr --- 2024-04-19 11:01:08,029 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-7500 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-19 11:01:08] authn-users-7500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-19 11:01:08,029 INFO 2024-04-19 11:01:08,029 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:01:08,029 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:08,338 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:08,338 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:08,338 DEBUG --- stdout --- 2024-04-19 11:01:08,338 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:08,338 DEBUG --- stderr --- 2024-04-19 11:01:08,338 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/order.json { "name": "authn-users-7500", "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-7500 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-7500", "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=7500 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-19 11:01:08] authn-users-7500 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:01:08,340 INFO 2024-04-19 11:01:08,340 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-19 11:01:08,340 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 11:01:08,572 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:01:08,572 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:08,572 DEBUG --- stdout --- 2024-04-19 11:01:08,572 DEBUG WAITING 2024-04-19 11:01:08,572 DEBUG --- stderr --- 2024-04-19 11:01:08,572 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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-19 11:01:08] authn-users-7500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-19 11:01:08,573 INFO 2024-04-19 11:01:08,573 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:01:08,573 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:08,910 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:08,912 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:08,912 DEBUG --- stdout --- 2024-04-19 11:01:08,913 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:08,913 DEBUG --- stderr --- 2024-04-19 11:01:08,913 DEBUG 2024-04-19 11:01:08,913 INFO 2024-04-19 11:01:08,913 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/order.json overseer-0-5c67bf94db-77mh9:/results/orders/order.json 2024-04-19 11:01:08,913 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:01:10,352 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:10,352 DEBUG --- stdout --- 2024-04-19 11:01:10,352 DEBUG 2024-04-19 11:01:10,352 DEBUG --- stderr --- 2024-04-19 11:01:10,352 DEBUG Order has been successfully forwarded to overseer [2024-04-19 11:01:10] - INFO: Check if Gatling simulation started successfully 2024-04-19 11:01:10,353 INFO 2024-04-19 11:01:10,353 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:01:10,353 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:10,668 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:10,668 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:10,668 DEBUG --- stdout --- 2024-04-19 11:01:10,668 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:10,668 DEBUG --- stderr --- 2024-04-19 11:01:10,668 DEBUG 2024-04-19 11:01:10,669 INFO 2024-04-19 11:01:10,669 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-7500/stdout.txt | grep "started..." 2024-04-19 11:01:10,669 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 11:01:11,429 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-19 11:01:17,207 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-19 11:01:22,963 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-19 11:01:22,963 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:22,963 DEBUG --- stdout --- 2024-04-19 11:01:22,964 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:01:22,964 DEBUG --- stderr --- 2024-04-19 11:01:22,964 DEBUG ________________________________________________________________________________ [2024-04-19 11:01:22] authn-users-7500 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 06s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry PASS : Order stopped by itself after 40m 06s and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-19 11:41:29] authn-users-7500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-19 11:41:29,961 INFO 2024-04-19 11:41:29,961 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:29,961 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:30,309 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:30,309 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:30,309 DEBUG --- stdout --- 2024-04-19 11:41:30,309 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:30,310 DEBUG --- stderr --- 2024-04-19 11:41:30,310 DEBUG 2024-04-19 11:41:30,310 INFO 2024-04-19 11:41:30,310 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:41:30,310 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:41:31,131 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:31,132 DEBUG --- stdout --- 2024-04-19 11:41:31,132 DEBUG 2024-04-19 11:41:31,132 DEBUG --- stderr --- 2024-04-19 11:41:31,132 DEBUG Order ran during 40m 20s ________________________________________________________________________________ [2024-04-19 11:41:31] authn-users-7500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-19 11:41:31] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/order.json 2024-04-19 11:41:31,133 INFO 2024-04-19 11:41:31,133 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:31,133 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:31,457 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:31,457 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:31,457 DEBUG --- stdout --- 2024-04-19 11:41:31,457 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:31,457 DEBUG --- stderr --- 2024-04-19 11:41:31,457 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:41:31,457 INFO 2024-04-19 11:41:31,457 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:31,458 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:31,777 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:31,778 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:31,778 DEBUG --- stdout --- 2024-04-19 11:41:31,778 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:31,778 DEBUG --- stderr --- 2024-04-19 11:41:31,778 DEBUG 2024-04-19 11:41:31,778 INFO 2024-04-19 11:41:31,779 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-7500/rc.txt" ]; then echo "/results/orders/authn-users-7500/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-7500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7500/rc.txt file found" 2024-04-19 11:41:31,779 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:41:32,616 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:41:32,616 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:32,616 DEBUG --- stdout --- 2024-04-19 11:41:32,616 DEBUG /results/orders/authn-users-7500/rc.txt file found 2024-04-19 11:41:32,617 DEBUG --- stderr --- 2024-04-19 11:41:32,617 DEBUG 2024-04-19 11:41:32,617 INFO 2024-04-19 11:41:32,617 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:32,617 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:32,979 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:32,979 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:32,979 DEBUG --- stdout --- 2024-04-19 11:41:32,980 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:32,980 DEBUG --- stderr --- 2024-04-19 11:41:32,980 DEBUG 2024-04-19 11:41:32,980 INFO 2024-04-19 11:41:32,980 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-7500/rc.txt 2024-04-19 11:41:33,804 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:41:33,804 DEBUG --- stdout --- 2024-04-19 11:41:33,804 DEBUG 4 /results/orders/authn-users-7500/rc.txt 2024-04-19 11:41:33,805 DEBUG --- stderr --- 2024-04-19 11:41:33,805 DEBUG 2024-04-19 11:41:33,805 INFO - Download file (size 4k) 2024-04-19 11:41:33,805 INFO 2024-04-19 11:41:33,805 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:33,805 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:34,202 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:34,203 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:34,203 DEBUG --- stdout --- 2024-04-19 11:41:34,203 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:34,203 DEBUG --- stderr --- 2024-04-19 11:41:34,203 DEBUG source : /results/orders/authn-users-7500/rc.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/rc.txt 2024-04-19 11:41:34,203 INFO 2024-04-19 11:41:34,203 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:41:34,203 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:34,593 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:34,594 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:34,594 DEBUG --- stdout --- 2024-04-19 11:41:34,594 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:34,594 DEBUG --- stderr --- 2024-04-19 11:41:34,594 DEBUG 2024-04-19 11:41:34,594 INFO 2024-04-19 11:41:34,598 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-7500/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/rc.txt 2024-04-19 11:41:34,599 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:41:35,436 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:35,436 DEBUG --- stdout --- 2024-04-19 11:41:35,436 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:41:35,436 DEBUG --- stderr --- 2024-04-19 11:41:35,436 DEBUG 2024-04-19 11:41:35,436 INFO 2024-04-19 11:41:35,436 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:35,436 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:35,738 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:35,738 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:35,738 DEBUG --- stdout --- 2024-04-19 11:41:35,738 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:35,738 DEBUG --- stderr --- 2024-04-19 11:41:35,738 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:41:35,738 INFO 2024-04-19 11:41:35,738 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:35,739 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:36,059 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:36,059 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:36,059 DEBUG --- stdout --- 2024-04-19 11:41:36,059 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:36,059 DEBUG --- stderr --- 2024-04-19 11:41:36,059 DEBUG 2024-04-19 11:41:36,060 INFO 2024-04-19 11:41:36,060 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-7500/stderr.txt" ]; then echo "/results/orders/authn-users-7500/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-7500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7500/stderr.txt file found" 2024-04-19 11:41:36,060 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:41:36,898 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:41:36,898 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:36,898 DEBUG --- stdout --- 2024-04-19 11:41:36,898 DEBUG /results/orders/authn-users-7500/stderr.txt file found 2024-04-19 11:41:36,898 DEBUG --- stderr --- 2024-04-19 11:41:36,899 DEBUG 2024-04-19 11:41:36,899 INFO 2024-04-19 11:41:36,899 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:36,899 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:37,221 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:37,222 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:37,222 DEBUG --- stdout --- 2024-04-19 11:41:37,222 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:37,222 DEBUG --- stderr --- 2024-04-19 11:41:37,222 DEBUG 2024-04-19 11:41:37,222 INFO 2024-04-19 11:41:37,222 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-7500/stderr.txt 2024-04-19 11:41:38,057 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:41:38,057 DEBUG --- stdout --- 2024-04-19 11:41:38,057 DEBUG 0 /results/orders/authn-users-7500/stderr.txt 2024-04-19 11:41:38,057 DEBUG --- stderr --- 2024-04-19 11:41:38,057 DEBUG 2024-04-19 11:41:38,057 INFO - Download file (size 0k) 2024-04-19 11:41:38,058 INFO 2024-04-19 11:41:38,058 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:38,058 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:38,387 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:38,387 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:38,387 DEBUG --- stdout --- 2024-04-19 11:41:38,387 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:38,387 DEBUG --- stderr --- 2024-04-19 11:41:38,387 DEBUG source : /results/orders/authn-users-7500/stderr.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/stderr.txt 2024-04-19 11:41:38,388 INFO 2024-04-19 11:41:38,388 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:38,388 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:38,714 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:38,715 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:38,715 DEBUG --- stdout --- 2024-04-19 11:41:38,715 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:38,715 DEBUG --- stderr --- 2024-04-19 11:41:38,715 DEBUG 2024-04-19 11:41:38,716 INFO 2024-04-19 11:41:38,716 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-7500/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/stderr.txt 2024-04-19 11:41:38,716 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:41:39,586 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:39,586 DEBUG --- stdout --- 2024-04-19 11:41:39,586 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:41:39,586 DEBUG --- stderr --- 2024-04-19 11:41:39,587 DEBUG 2024-04-19 11:41:39,587 INFO 2024-04-19 11:41:39,588 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:39,588 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:39,929 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:39,931 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:39,932 DEBUG --- stdout --- 2024-04-19 11:41:39,932 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:39,932 DEBUG --- stderr --- 2024-04-19 11:41:39,932 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:41:39,933 INFO 2024-04-19 11:41:39,933 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:39,933 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:40,320 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:40,321 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:40,321 DEBUG --- stdout --- 2024-04-19 11:41:40,321 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:40,321 DEBUG --- stderr --- 2024-04-19 11:41:40,321 DEBUG 2024-04-19 11:41:40,322 INFO 2024-04-19 11:41:40,322 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-7500/stdout.txt" ]; then echo "/results/orders/authn-users-7500/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-7500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7500/stdout.txt file found" 2024-04-19 11:41:40,322 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:41:41,214 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:41:41,214 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:41,214 DEBUG --- stdout --- 2024-04-19 11:41:41,214 DEBUG /results/orders/authn-users-7500/stdout.txt file found 2024-04-19 11:41:41,214 DEBUG --- stderr --- 2024-04-19 11:41:41,214 DEBUG 2024-04-19 11:41:41,215 INFO 2024-04-19 11:41:41,215 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:41,215 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:41,514 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:41,514 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:41,514 DEBUG --- stdout --- 2024-04-19 11:41:41,514 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:41,514 DEBUG --- stderr --- 2024-04-19 11:41:41,515 DEBUG 2024-04-19 11:41:41,515 INFO 2024-04-19 11:41:41,515 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-7500/stdout.txt 2024-04-19 11:41:42,330 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:41:42,330 DEBUG --- stdout --- 2024-04-19 11:41:42,331 DEBUG 328 /results/orders/authn-users-7500/stdout.txt 2024-04-19 11:41:42,331 DEBUG --- stderr --- 2024-04-19 11:41:42,331 DEBUG 2024-04-19 11:41:42,331 INFO - Download file (size 328k) 2024-04-19 11:41:42,331 INFO 2024-04-19 11:41:42,331 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:42,331 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:42,629 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:42,629 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:42,630 DEBUG --- stdout --- 2024-04-19 11:41:42,630 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:42,630 DEBUG --- stderr --- 2024-04-19 11:41:42,630 DEBUG source : /results/orders/authn-users-7500/stdout.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/stdout.txt 2024-04-19 11:41:42,630 INFO 2024-04-19 11:41:42,630 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:42,630 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:42,931 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:42,931 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:42,931 DEBUG --- stdout --- 2024-04-19 11:41:42,931 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:42,931 DEBUG --- stderr --- 2024-04-19 11:41:42,931 DEBUG 2024-04-19 11:41:42,932 INFO 2024-04-19 11:41:42,932 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-7500/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/stdout.txt 2024-04-19 11:41:42,932 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:41:43,940 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:43,940 DEBUG --- stdout --- 2024-04-19 11:41:43,940 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:41:43,940 DEBUG --- stderr --- 2024-04-19 11:41:43,941 DEBUG ________________________________________________________________________________ [2024-04-19 11:41:43] authn-users-7500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-19 11:41:43,941 INFO 2024-04-19 11:41:43,942 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:43,942 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:44,265 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:44,265 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:44,265 DEBUG --- stdout --- 2024-04-19 11:41:44,266 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:44,266 DEBUG --- stderr --- 2024-04-19 11:41:44,266 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:41:44,266 INFO 2024-04-19 11:41:44,266 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:44,266 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:44,623 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:44,624 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:44,624 DEBUG --- stdout --- 2024-04-19 11:41:44,624 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:44,624 DEBUG --- stderr --- 2024-04-19 11:41:44,624 DEBUG 2024-04-19 11:41:44,624 INFO 2024-04-19 11:41:44,624 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-7500/cmd.txt" ]; then echo "/results/orders/authn-users-7500/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-7500/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7500/cmd.txt file found" 2024-04-19 11:41:44,624 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:41:45,464 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:41:45,466 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:45,466 DEBUG --- stdout --- 2024-04-19 11:41:45,466 DEBUG /results/orders/authn-users-7500/cmd.txt file found 2024-04-19 11:41:45,466 DEBUG --- stderr --- 2024-04-19 11:41:45,466 DEBUG 2024-04-19 11:41:45,467 INFO 2024-04-19 11:41:45,467 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:45,467 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:45,816 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:45,816 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:45,816 DEBUG --- stdout --- 2024-04-19 11:41:45,817 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:45,817 DEBUG --- stderr --- 2024-04-19 11:41:45,817 DEBUG 2024-04-19 11:41:45,817 INFO 2024-04-19 11:41:45,817 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-7500/cmd.txt 2024-04-19 11:41:46,662 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:41:46,662 DEBUG --- stdout --- 2024-04-19 11:41:46,663 DEBUG 4 /results/orders/authn-users-7500/cmd.txt 2024-04-19 11:41:46,663 DEBUG --- stderr --- 2024-04-19 11:41:46,663 DEBUG 2024-04-19 11:41:46,663 INFO - Download file (size 4k) 2024-04-19 11:41:46,663 INFO 2024-04-19 11:41:46,663 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:46,664 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:48,871 INFO [loop_until]: Function succeeded after 2s (rc=0) - expected number of elements found 2024-04-19 11:41:48,871 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:48,871 DEBUG --- stdout --- 2024-04-19 11:41:48,871 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:48,871 DEBUG --- stderr --- 2024-04-19 11:41:48,871 DEBUG source : /results/orders/authn-users-7500/cmd.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/cmd.txt 2024-04-19 11:41:48,872 INFO 2024-04-19 11:41:48,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:48,872 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:41:49,188 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:41:49,189 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:49,189 DEBUG --- stdout --- 2024-04-19 11:41:49,189 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:41:49,189 DEBUG --- stderr --- 2024-04-19 11:41:49,189 DEBUG 2024-04-19 11:41:49,189 INFO 2024-04-19 11:41:49,189 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-7500/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/cmd.txt 2024-04-19 11:41:49,189 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:41:50,005 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:41:50,005 DEBUG --- stdout --- 2024-04-19 11:41:50,005 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:41:50,005 DEBUG --- stderr --- 2024-04-19 11:41:50,005 DEBUG 2024-04-19 11:41:50,006 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/cmd.txt is small (1 lines), printing all lines: 2024-04-19 11:41:50,006 DEBUG ----- output ----- 2024-04-19 11:41:50,006 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-7500 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-19 11:41:50,006 INFO ________________________________________________________________________________ [2024-04-19 11:41:49] authn-users-7500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-19 11:41:50,006 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/rc.txt is small (1 lines), printing all lines: 2024-04-19 11:41:50,006 DEBUG ----- output ----- 2024-04-19 11:41:50,006 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-19 11:41:50,006 INFO ________________________________________________________________________________ [2024-04-19 11:41:50] authn-users-7500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-19 11:41:50,007 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/stdout.txt is 5622 lines): 2024-04-19 11:41:50,007 DEBUG ----- output ----- 2024-04-19 11:41:50,007 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-19 11:41:50,007 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-19 11:41:50,007 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 11:41:50,007 INFO 2024-04-19 11:41:50,008 INFO ================================================================================ 2024-04-19 11:41:50,008 INFO 2024-04-19 12:01:24 5s elapsed 2024-04-19 11:41:50,008 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:41:50,008 INFO > Global (OK=3133 KO=0 ) 2024-04-19 11:41:50,008 INFO > userRestLogin (OK=3133 KO=0 ) 2024-04-19 11:41:50,008 INFO 2024-04-19 11:41:50,008 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:41:50,008 INFO [- ] 0% 2024-04-19 11:41:50,008 INFO waiting: 27112027 / active: 13 / done: 3133 2024-04-19 11:41:50,008 INFO ================================================================================ 2024-04-19 11:41:50,008 INFO 2024-04-19 11:41:50,008 INFO 2024-04-19 11:41:50,008 INFO ================================================================================ 2024-04-19 11:41:50,008 INFO 2024-04-19 12:01:29 10s elapsed 2024-04-19 11:41:50,008 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:41:50,008 INFO > Global (OK=12510 KO=0 ) 2024-04-19 11:41:50,008 INFO > userRestLogin (OK=12510 KO=0 ) 2024-04-19 11:41:50,008 INFO 2024-04-19 11:41:50,008 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:41:50,008 INFO [- ] 0% 2024-04-19 11:41:50,008 INFO waiting: 27102636 / active: 28 / done: 12509 2024-04-19 11:41:50,008 INFO ================================================================================ 2024-04-19 11:41:50,008 INFO 2024-04-19 11:41:50,008 INFO 2024-04-19 11:41:50,008 INFO ================================================================================ 2024-04-19 11:41:50,008 INFO 2024-04-19 12:01:34 15s elapsed 2024-04-19 11:41:50,008 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:41:50,008 INFO > Global (OK=28059 KO=0 ) 2024-04-19 11:41:50,008 INFO > userRestLogin (OK=28059 KO=0 ) 2024-04-19 11:41:50,008 INFO 2024-04-19 11:41:50,008 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:41:50,008 INFO [- ] 0% 2024-04-19 11:41:50,008 INFO waiting: 27086999 / active: 117 / done: 28057 2024-04-19 11:41:50,008 INFO ================================================================================ 2024-04-19 11:41:50,008 INFO 2024-04-19 11:41:50,008 INFO 2024-04-19 11:41:50,008 INFO ================================================================================ 2024-04-19 11:41:50,008 INFO 2024-04-19 12:01:39 20s elapsed 2024-04-19 11:41:50,008 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:41:50,008 INFO > Global (OK=49888 KO=0 ) 2024-04-19 11:41:50,008 INFO > userRestLogin (OK=49888 KO=0 ) 2024-04-19 11:41:50,009 INFO 2024-04-19 11:41:50,009 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:41:50,009 INFO [- ] 0% 2024-04-19 11:41:50,009 INFO waiting: 27065106 / active: 179 / done: 49888 2024-04-19 11:41:50,009 INFO ================================================================================ 2024-04-19 11:41:50,009 INFO 2024-04-19 11:41:50,009 INFO 2024-04-19 11:41:50,009 INFO ================================================================================ 2024-04-19 11:41:50,009 INFO 2024-04-19 12:01:44 25s elapsed 2024-04-19 11:41:50,009 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:41:50,009 INFO > Global (OK=78029 KO=0 ) 2024-04-19 11:41:50,009 INFO > userRestLogin (OK=78029 KO=0 ) 2024-04-19 11:41:50,009 INFO 2024-04-19 11:41:50,009 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:41:50,009 INFO [- ] 0% 2024-04-19 11:41:50,009 INFO waiting: 27036958 / active: 188 / done: 78027 2024-04-19 11:41:50,009 INFO ================================================================================ 2024-04-19 11:41:50,009 INFO 2024-04-19 11:41:50,009 INFO 2024-04-19 11:41:50,009 INFO ================================================================================ 2024-04-19 11:41:50,009 INFO 2024-04-19 12:01:49 30s elapsed 2024-04-19 11:41:50,009 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:41:50,009 INFO > Global (OK=112107 KO=0 ) 2024-04-19 11:41:50,009 INFO > userRestLogin (OK=112107 KO=0 ) 2024-04-19 11:41:50,009 INFO 2024-04-19 11:41:50,009 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:41:50,009 INFO [- ] 0% 2024-04-19 11:41:50,009 INFO waiting: 27002707 / active: 364 / done: 112102 2024-04-19 11:41:50,009 INFO ================================================================================ 2024-04-19 11:41:50,009 INFO 2024-04-19 11:41:50,009 INFO 2024-04-19 11:41:50,009 INFO ================================================================================ 2024-04-19 11:41:50,009 INFO 2024-04-19 12:01:54 35s elapsed 2024-04-19 11:41:50,009 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:41:50,009 INFO > Global (OK=149057 KO=0 ) 2024-04-19 11:41:50,009 INFO > userRestLogin (OK=149057 KO=0 ) 2024-04-19 11:41:50,009 INFO 2024-04-19 11:41:50,009 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:41:50,009 INFO [- ] 0% 2024-04-19 11:41:50,009 INFO waiting: 26965127 / active: 993 / done: 149053 2024-04-19 11:41:50,009 INFO ================================================================================ 2024-04-19 11:41:50,010 INFO 2024-04-19 11:41:50,010 INFO 2024-04-19 11:41:50,010 INFO ================================================================================ 2024-04-19 11:41:50,010 INFO 2024-04-19 12:01:59 40s elapsed 2024-04-19 11:41:50,010 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:41:50,010 INFO > Global (OK=186567 KO=0 ) 2024-04-19 11:41:50,010 INFO > userRestLogin (OK=186567 KO=0 ) 2024-04-19 11:41:50,010 INFO 2024-04-19 11:41:50,010 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:41:50,010 INFO [- ] 0% 2024-04-19 11:41:50,010 INFO waiting: 26927747 / active: 859 / done: 186567 2024-04-19 11:41:50,010 INFO ================================================================================ 2024-04-19 11:41:50,010 INFO 2024-04-19 11:41:50,010 INFO 2024-04-19 11:41:50,010 INFO [...] 2024-04-19 11:41:50,011 INFO 2024-04-19 11:41:50,011 INFO 12:39:40.436 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'userRestLogin' failed for user 16441108: Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:41:50,011 INFO 12:39:40.459 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-04-19 11:41:50,011 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-04-19 11:41:50,012 INFO Request: 2024-04-19 11:41:50,012 INFO userRestLogin: KO Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:41:50,012 INFO ========================= 2024-04-19 11:41:50,012 INFO Session: 2024-04-19 11:41:50,012 INFO Session(am.AMAuthN,16441108,HashMap(gatling.http.cache.baseUrl -> https://xlou.iam.xlou-cdm.engineeringpit.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@5c666f6c, username -> user.89456, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@425bc8df, password -> T35tr0ck123),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$789/0x00007fd7506b9338@662d2b2c,io.netty.channel.epoll.EpollEventLoop@1a1d3c1a) 2024-04-19 11:41:50,012 INFO ========================= 2024-04-19 11:41:50,012 INFO HTTP request: 2024-04-19 11:41:50,012 INFO POST https://xlou.iam.xlou-cdm.engineeringpit.com/am/json/realms/root/authenticate 2024-04-19 11:41:50,012 INFO headers: 2024-04-19 11:41:50,012 INFO Accept-API-Version: resource=2.0, protocol=1.0 2024-04-19 11:41:50,012 INFO X-OpenAM-Username: user.89456 2024-04-19 11:41:50,012 INFO X-OpenAM-Password: T35tr0ck123 2024-04-19 11:41:50,012 INFO accept: */* 2024-04-19 11:41:50,012 INFO host: xlou.iam.xlou-cdm.engineeringpit.com 2024-04-19 11:41:50,012 INFO content-length: 0 2024-04-19 11:41:50,012 INFO ========================= 2024-04-19 11:41:50,012 INFO HTTP response: 2024-04-19 11:41:50,012 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-04-19 11:41:50,012 INFO 12:39:40.460 [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:41:50,012 INFO 12:39:40.471 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'userRestLogin' failed for user 16444804: Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:41:50,012 INFO 12:39:40.472 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-04-19 11:41:50,012 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-04-19 11:41:50,012 INFO Request: 2024-04-19 11:41:50,012 INFO userRestLogin: KO Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:41:50,012 INFO ========================= 2024-04-19 11:41:50,012 INFO Session: 2024-04-19 11:41:50,012 INFO Session(am.AMAuthN,16444804,HashMap(gatling.http.cache.baseUrl -> https://xlou.iam.xlou-cdm.engineeringpit.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@77ae2140, username -> user.817431, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@25a3ce96, password -> T35tr0ck123),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$789/0x00007fd7506b9338@662d2b2c,io.netty.channel.epoll.EpollEventLoop@1a1d3c1a) 2024-04-19 11:41:50,012 INFO ========================= 2024-04-19 11:41:50,012 INFO HTTP request: 2024-04-19 11:41:50,012 INFO POST https://xlou.iam.xlou-cdm.engineeringpit.com/am/json/realms/root/authenticate 2024-04-19 11:41:50,012 INFO headers: 2024-04-19 11:41:50,012 INFO Accept-API-Version: resource=2.0, protocol=1.0 2024-04-19 11:41:50,012 INFO X-OpenAM-Username: user.817431 2024-04-19 11:41:50,012 INFO X-OpenAM-Password: T35tr0ck123 2024-04-19 11:41:50,012 INFO accept: */* 2024-04-19 11:41:50,012 INFO host: xlou.iam.xlou-cdm.engineeringpit.com 2024-04-19 11:41:50,012 INFO content-length: 0 2024-04-19 11:41:50,012 INFO ========================= 2024-04-19 11:41:50,012 INFO HTTP response: 2024-04-19 11:41:50,012 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-04-19 11:41:50,013 INFO 12:39:40.472 [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:41:50,013 INFO 12:39:40.472 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'userRestLogin' failed for user 16445364: Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:41:50,013 INFO 12:39:40.473 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-04-19 11:41:50,013 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-04-19 11:41:50,013 INFO Request: 2024-04-19 11:41:50,013 INFO userRestLogin: KO Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148.47.242:443 after 60000 ms 2024-04-19 11:41:50,013 INFO ========================= 2024-04-19 11:41:50,013 INFO Session: 2024-04-19 11:41:50,013 INFO Session(am.AMAuthN,16445364,HashMap(gatling.http.cache.baseUrl -> https://xlou.iam.xlou-cdm.engineeringpit.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@2e46523e, username -> user.520910, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@58bb0897, password -> T35tr0ck123),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$789/0x00007fd7506b9338@662d2b2c,io.netty.channel.epoll.EpollEventLoop@1a1d3c1a) 2024-04-19 11:41:50,013 INFO ========================= 2024-04-19 11:41:50,013 INFO HTTP request: 2024-04-19 11:41:50,013 INFO POST https://xlou.iam.xlou-cdm.engineeringpit.com/am/json/realms/root/authenticate 2024-04-19 11:41:50,013 INFO headers: 2024-04-19 11:41:50,013 INFO Accept-API-Version: resource=2.0, protocol=1.0 2024-04-19 11:41:50,013 INFO X-OpenAM-Username: user.520910 2024-04-19 11:41:50,013 INFO X-OpenAM-Password: T35tr0ck123 2024-04-19 11:41:50,013 INFO accept: */* 2024-04-19 11:41:50,013 INFO host: xlou.iam.xlou-cdm.engineeringpit.com 2024-04-19 11:41:50,013 INFO content-length: 0 2024-04-19 11:41:50,013 INFO ========================= 2024-04-19 11:41:50,013 INFO HTTP response: 2024-04-19 11:41:50,013 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-04-19 11:41:50,013 INFO 12:39:40.473 [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:41:50,013 INFO 2024-04-19 11:41:50,013 INFO ================================================================================ 2024-04-19 11:41:50,013 INFO 2024-04-19 12:39:44 2305s elapsed 2024-04-19 11:41:50,013 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:41:50,013 INFO > Global (OK=16414930 KO=3 ) 2024-04-19 11:41:50,013 INFO > userRestLogin (OK=16414930 KO=3 ) 2024-04-19 11:41:50,013 INFO ---- Errors -------------------------------------------------------------------- 2024-04-19 11:41:50,013 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 3 (100.0%) 2024-04-19 11:41:50,013 INFO .47.242:443 after 60000 ms 2024-04-19 11:41:50,013 INFO 2024-04-19 11:41:50,013 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:41:50,013 INFO [############################################-- ] 60% 2024-04-19 11:41:50,013 INFO waiting: 10266174 / active: 434067 / done: 16414932 2024-04-19 11:41:50,013 INFO ================================================================================ 2024-04-19 11:41:50,013 INFO 2024-04-19 11:41:50,013 INFO 2024-04-19 11:41:50,013 INFO ---------------------- 2024-04-19 11:41:50,013 INFO [19/Apr/2024 12:39:48] Create tar file authn-users-7500.tar.gz 2024-04-19 11:41:50,014 INFO ---------------------- 2024-04-19 11:41:50,014 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-19 11:41:50,014 INFO RESULT_FOLDER=authn-users-7500 2024-04-19 11:41:50,014 INFO SIMULATION_PATH=authn-users-7500/loadtest-20240419110118401 2024-04-19 11:41:50,014 INFO PWD=/results/gatling/authn-users-7500/loadtest-20240419110118401 2024-04-19 11:41:50,014 INFO [cmd] tar -czvf /results/gatling//authn-users-7500.tar.gz --exclude=simulation.log * 2024-04-19 11:41:50,014 INFO /results/gatling 2024-04-19 11:41:50,014 INFO 2024-04-19 11:41:50,014 INFO ---------------------- 2024-04-19 11:41:50,014 INFO [19/Apr/2024 12:39:48] Update /results/gatling//latest 2024-04-19 11:41:50,014 INFO ---------------------- 2024-04-19 11:41:50,014 INFO 2024-04-19 11:41:50,014 INFO ---------------------- 2024-04-19 11:41:50,014 INFO [19/Apr/2024 12:39:48] Update /results/gatling//latest.tar.gz 2024-04-19 11:41:50,014 INFO ---------------------- 2024-04-19 11:41:50,014 INFO ________________________________________________________________________________ [2024-04-19 11:41:50] authn-users-7500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-19 11:41:50,014 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-7500/stderr.txt is small (0 lines), printing all lines: 2024-04-19 11:41:50,014 DEBUG ----- output ----- 2024-04-19 11:41:50,014 DEBUG 2024-04-19 11:41:50,014 INFO ________________________________________________________________________________ [2024-04-19 11:41:50] authn-users-7500 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:41:50] authn-users-7500 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-19 11:41:50] authn-users-7500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped