--Task-- name: authn-users-4500 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-4000'] preceding_task: authn-users-4000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 4500, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-11 06:01:13] authn-users-4500 pre : N/A ________________________________________________________________________________ [2024-04-11 06:01:13] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-11 06:01:13] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-4500/stdout.txt Pod log : /results/orders/authn-users-4500 2024-04-11 06:01:13,875 INFO 2024-04-11 06:01:13,875 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-11 06:01:13,875 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:14,182 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:14,183 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:14,183 DEBUG --- stdout --- 2024-04-11 06:01:14,183 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:14,183 DEBUG --- stderr --- 2024-04-11 06:01:14,183 DEBUG ________________________________________________________________________________ [2024-04-11 06:01:14] authn-users-4500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-11 06:01:14,183 INFO 2024-04-11 06:01: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-11 06:01:14,183 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:14,492 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:14,492 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:14,492 DEBUG --- stdout --- 2024-04-11 06:01:14,492 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:14,492 DEBUG --- stderr --- 2024-04-11 06:01:14,492 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-4500 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-11 06:01:14] authn-users-4500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-11 06:01:14,493 INFO 2024-04-11 06:01:14,493 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-11 06:01:14,493 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:14,801 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:14,801 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:14,801 DEBUG --- stdout --- 2024-04-11 06:01:14,802 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:14,802 DEBUG --- stderr --- 2024-04-11 06:01:14,802 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/order.json { "name": "authn-users-4500", "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-4500 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-4500", "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=4500 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-11 06:01:14] authn-users-4500 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-11 06:01:14,803 INFO 2024-04-11 06:01:14,804 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-11 06:01:14,804 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-11 06:01:15,050 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 06:01:15,050 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:15,051 DEBUG --- stdout --- 2024-04-11 06:01:15,051 DEBUG WAITING 2024-04-11 06:01:15,051 DEBUG --- stderr --- 2024-04-11 06:01:15,051 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-11 06:01:15] authn-users-4500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-11 06:01:15,051 INFO 2024-04-11 06:01:15,051 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-11 06:01:15,051 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:15,382 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:15,382 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:15,382 DEBUG --- stdout --- 2024-04-11 06:01:15,382 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:15,382 DEBUG --- stderr --- 2024-04-11 06:01:15,382 DEBUG 2024-04-11 06:01:15,383 INFO 2024-04-11 06:01:15,383 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/05_authn-users-4500/order.json overseer-0-78845f4f8c-v2r2q:/results/orders/order.json 2024-04-11 06:01:15,383 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 06:01:16,805 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:16,806 DEBUG --- stdout --- 2024-04-11 06:01:16,806 DEBUG 2024-04-11 06:01:16,806 DEBUG --- stderr --- 2024-04-11 06:01:16,806 DEBUG Order has been successfully forwarded to overseer [2024-04-11 06:01:16] - INFO: Check if Gatling simulation started successfully 2024-04-11 06:01:16,806 INFO 2024-04-11 06:01:16,806 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-11 06:01:16,806 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:01:17,115 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:01:17,115 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:17,116 DEBUG --- stdout --- 2024-04-11 06:01:17,116 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:01:17,116 DEBUG --- stderr --- 2024-04-11 06:01:17,116 DEBUG 2024-04-11 06:01:17,116 INFO 2024-04-11 06:01:17,116 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q -- head -n 25 /results/orders/authn-users-4500/stdout.txt | grep "started..." 2024-04-11 06:01:17,116 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-11 06:01:17,885 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-11 06:01:23,708 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-11 06:01:29,564 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-11 06:01:29,564 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:01:29,564 DEBUG --- stdout --- 2024-04-11 06:01:29,564 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-11 07:01:29 5s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=1865 KO=0 ) > userRestLogin (OK=1865 KO=0 ) ---- am.AMAuthN ---------------------------------------------------------------- [- ] 0% waiting: 16266256 / active: 15 / done: 1865 ================================================================================ 2024-04-11 06:01:29,564 DEBUG --- stderr --- 2024-04-11 06:01:29,564 DEBUG ________________________________________________________________________________ [2024-04-11 06:01:29] authn-users-4500 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 03s, retry PASS : Order stopped by itself after 18m 03s and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-11 06:19:33] authn-users-4500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-11 06:19:33,058 INFO 2024-04-11 06:19:33,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-11 06:19:33,058 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:33,419 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:33,419 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:33,419 DEBUG --- stdout --- 2024-04-11 06:19:33,419 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:33,419 DEBUG --- stderr --- 2024-04-11 06:19:33,419 DEBUG 2024-04-11 06:19:33,419 INFO 2024-04-11 06:19:33,419 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q -- rm -f /results/orders/order.json 2024-04-11 06:19:33,419 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 06:19:34,239 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:34,239 DEBUG --- stdout --- 2024-04-11 06:19:34,239 DEBUG 2024-04-11 06:19:34,239 DEBUG --- stderr --- 2024-04-11 06:19:34,239 DEBUG Order ran during 18m 17s ________________________________________________________________________________ [2024-04-11 06:19:34] authn-users-4500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-11 06:19:34] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/order.json 2024-04-11 06:19:34,240 INFO 2024-04-11 06:19:34,240 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-11 06:19:34,240 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:34,553 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:34,553 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:34,554 DEBUG --- stdout --- 2024-04-11 06:19:34,554 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:34,554 DEBUG --- stderr --- 2024-04-11 06:19:34,554 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 06:19:34,554 INFO 2024-04-11 06:19:34,554 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-11 06:19:34,554 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:34,862 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:34,862 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:34,862 DEBUG --- stdout --- 2024-04-11 06:19:34,862 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:34,862 DEBUG --- stderr --- 2024-04-11 06:19:34,862 DEBUG 2024-04-11 06:19:34,863 INFO 2024-04-11 06:19:34,863 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4500/rc.txt" ]; then echo "/results/orders/authn-users-4500/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-4500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4500/rc.txt file found" 2024-04-11 06:19:34,863 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 06:19:35,730 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 06:19:35,730 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:35,730 DEBUG --- stdout --- 2024-04-11 06:19:35,730 DEBUG /results/orders/authn-users-4500/rc.txt file found 2024-04-11 06:19:35,730 DEBUG --- stderr --- 2024-04-11 06:19:35,730 DEBUG 2024-04-11 06:19:35,731 INFO 2024-04-11 06:19:35,731 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-11 06:19:35,731 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:36,045 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:36,046 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:36,046 DEBUG --- stdout --- 2024-04-11 06:19:36,046 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:36,046 DEBUG --- stderr --- 2024-04-11 06:19:36,046 DEBUG 2024-04-11 06:19:36,046 INFO 2024-04-11 06:19:36,046 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-4500/rc.txt 2024-04-11 06:19:36,883 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 06:19:36,883 DEBUG --- stdout --- 2024-04-11 06:19:36,883 DEBUG 4 /results/orders/authn-users-4500/rc.txt 2024-04-11 06:19:36,883 DEBUG --- stderr --- 2024-04-11 06:19:36,883 DEBUG 2024-04-11 06:19:36,883 INFO - Download file (size 4k) 2024-04-11 06:19:36,884 INFO 2024-04-11 06:19:36,884 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-11 06:19:36,884 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:37,194 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:37,194 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:37,194 DEBUG --- stdout --- 2024-04-11 06:19:37,194 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:37,194 DEBUG --- stderr --- 2024-04-11 06:19:37,194 DEBUG source : /results/orders/authn-users-4500/rc.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/rc.txt 2024-04-11 06:19:37,195 INFO 2024-04-11 06:19:37,195 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-11 06:19:37,195 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:37,532 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:37,533 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:37,533 DEBUG --- stdout --- 2024-04-11 06:19:37,533 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:37,533 DEBUG --- stderr --- 2024-04-11 06:19:37,533 DEBUG 2024-04-11 06:19:37,533 INFO 2024-04-11 06:19:37,533 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-4500/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/rc.txt 2024-04-11 06:19:37,533 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 06:19:38,314 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:38,314 DEBUG --- stdout --- 2024-04-11 06:19:38,314 DEBUG tar: Removing leading `/' from member names 2024-04-11 06:19:38,314 DEBUG --- stderr --- 2024-04-11 06:19:38,314 DEBUG 2024-04-11 06:19:38,315 INFO 2024-04-11 06:19:38,315 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-11 06:19:38,315 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:38,660 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:38,660 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:38,660 DEBUG --- stdout --- 2024-04-11 06:19:38,660 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:38,660 DEBUG --- stderr --- 2024-04-11 06:19:38,660 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 06:19:38,661 INFO 2024-04-11 06:19:38,661 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-11 06:19:38,661 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:39,004 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:39,005 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:39,005 DEBUG --- stdout --- 2024-04-11 06:19:39,005 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:39,005 DEBUG --- stderr --- 2024-04-11 06:19:39,005 DEBUG 2024-04-11 06:19:39,005 INFO 2024-04-11 06:19:39,005 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4500/stderr.txt" ]; then echo "/results/orders/authn-users-4500/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-4500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4500/stderr.txt file found" 2024-04-11 06:19:39,005 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 06:19:39,859 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 06:19:39,859 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:39,859 DEBUG --- stdout --- 2024-04-11 06:19:39,859 DEBUG /results/orders/authn-users-4500/stderr.txt file found 2024-04-11 06:19:39,859 DEBUG --- stderr --- 2024-04-11 06:19:39,859 DEBUG 2024-04-11 06:19:39,860 INFO 2024-04-11 06:19:39,860 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-11 06:19:39,860 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:40,205 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:40,205 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:40,205 DEBUG --- stdout --- 2024-04-11 06:19:40,205 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:40,205 DEBUG --- stderr --- 2024-04-11 06:19:40,205 DEBUG 2024-04-11 06:19:40,205 INFO 2024-04-11 06:19:40,206 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-4500/stderr.txt 2024-04-11 06:19:41,044 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 06:19:41,044 DEBUG --- stdout --- 2024-04-11 06:19:41,044 DEBUG 0 /results/orders/authn-users-4500/stderr.txt 2024-04-11 06:19:41,044 DEBUG --- stderr --- 2024-04-11 06:19:41,044 DEBUG 2024-04-11 06:19:41,044 INFO - Download file (size 0k) 2024-04-11 06:19:41,045 INFO 2024-04-11 06:19:41,045 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-11 06:19:41,045 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:41,367 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:41,367 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:41,367 DEBUG --- stdout --- 2024-04-11 06:19:41,367 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:41,367 DEBUG --- stderr --- 2024-04-11 06:19:41,367 DEBUG source : /results/orders/authn-users-4500/stderr.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/stderr.txt 2024-04-11 06:19:41,367 INFO 2024-04-11 06:19:41,367 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-11 06:19:41,367 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:41,688 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:41,688 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:41,688 DEBUG --- stdout --- 2024-04-11 06:19:41,689 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:41,689 DEBUG --- stderr --- 2024-04-11 06:19:41,689 DEBUG 2024-04-11 06:19:41,689 INFO 2024-04-11 06:19:41,689 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-4500/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/stderr.txt 2024-04-11 06:19:41,689 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 06:19:42,449 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:42,449 DEBUG --- stdout --- 2024-04-11 06:19:42,450 DEBUG tar: Removing leading `/' from member names 2024-04-11 06:19:42,450 DEBUG --- stderr --- 2024-04-11 06:19:42,450 DEBUG 2024-04-11 06:19:42,450 INFO 2024-04-11 06:19:42,450 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-11 06:19:42,450 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:42,763 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:42,763 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:42,763 DEBUG --- stdout --- 2024-04-11 06:19:42,763 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:42,763 DEBUG --- stderr --- 2024-04-11 06:19:42,763 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 06:19:42,764 INFO 2024-04-11 06:19:42,764 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-11 06:19:42,764 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:43,075 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:43,075 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:43,075 DEBUG --- stdout --- 2024-04-11 06:19:43,075 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:43,075 DEBUG --- stderr --- 2024-04-11 06:19:43,075 DEBUG 2024-04-11 06:19:43,076 INFO 2024-04-11 06:19:43,076 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4500/stdout.txt" ]; then echo "/results/orders/authn-users-4500/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-4500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4500/stdout.txt file found" 2024-04-11 06:19:43,076 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 06:19:43,935 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 06:19:43,935 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:43,936 DEBUG --- stdout --- 2024-04-11 06:19:43,936 DEBUG /results/orders/authn-users-4500/stdout.txt file found 2024-04-11 06:19:43,936 DEBUG --- stderr --- 2024-04-11 06:19:43,936 DEBUG 2024-04-11 06:19:43,936 INFO 2024-04-11 06:19:43,936 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-11 06:19:43,936 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:44,332 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:44,332 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:44,332 DEBUG --- stdout --- 2024-04-11 06:19:44,332 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:44,332 DEBUG --- stderr --- 2024-04-11 06:19:44,333 DEBUG 2024-04-11 06:19:44,333 INFO 2024-04-11 06:19:44,333 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-4500/stdout.txt 2024-04-11 06:19:45,248 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 06:19:45,249 DEBUG --- stdout --- 2024-04-11 06:19:45,250 DEBUG 396 /results/orders/authn-users-4500/stdout.txt 2024-04-11 06:19:45,250 DEBUG --- stderr --- 2024-04-11 06:19:45,250 DEBUG 2024-04-11 06:19:45,250 INFO - Download file (size 396k) 2024-04-11 06:19:45,251 INFO 2024-04-11 06:19:45,251 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-11 06:19:45,251 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:45,578 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:45,578 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:45,578 DEBUG --- stdout --- 2024-04-11 06:19:45,578 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:45,578 DEBUG --- stderr --- 2024-04-11 06:19:45,578 DEBUG source : /results/orders/authn-users-4500/stdout.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/stdout.txt 2024-04-11 06:19:45,579 INFO 2024-04-11 06:19:45,579 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-11 06:19:45,579 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:45,909 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:45,909 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:45,909 DEBUG --- stdout --- 2024-04-11 06:19:45,909 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:45,909 DEBUG --- stderr --- 2024-04-11 06:19:45,909 DEBUG 2024-04-11 06:19:45,910 INFO 2024-04-11 06:19:45,910 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-4500/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/stdout.txt 2024-04-11 06:19:45,910 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 06:19:46,986 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:46,987 DEBUG --- stdout --- 2024-04-11 06:19:46,987 DEBUG tar: Removing leading `/' from member names 2024-04-11 06:19:46,987 DEBUG --- stderr --- 2024-04-11 06:19:46,987 DEBUG ________________________________________________________________________________ [2024-04-11 06:19:46] authn-users-4500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-11 06:19:46,987 INFO 2024-04-11 06:19:46,987 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-11 06:19:46,987 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:47,310 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:47,310 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:47,311 DEBUG --- stdout --- 2024-04-11 06:19:47,311 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:47,311 DEBUG --- stderr --- 2024-04-11 06:19:47,311 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 06:19:47,311 INFO 2024-04-11 06:19:47,311 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-11 06:19:47,311 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:47,639 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:47,640 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:47,640 DEBUG --- stdout --- 2024-04-11 06:19:47,640 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:47,640 DEBUG --- stderr --- 2024-04-11 06:19:47,640 DEBUG 2024-04-11 06:19:47,640 INFO 2024-04-11 06:19:47,640 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4500/cmd.txt" ]; then echo "/results/orders/authn-users-4500/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-4500/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4500/cmd.txt file found" 2024-04-11 06:19:47,640 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 06:19:48,490 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 06:19:48,491 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:48,491 DEBUG --- stdout --- 2024-04-11 06:19:48,491 DEBUG /results/orders/authn-users-4500/cmd.txt file found 2024-04-11 06:19:48,491 DEBUG --- stderr --- 2024-04-11 06:19:48,491 DEBUG 2024-04-11 06:19:48,491 INFO 2024-04-11 06:19:48,491 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-11 06:19:48,491 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:48,801 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:48,801 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:48,801 DEBUG --- stdout --- 2024-04-11 06:19:48,801 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:48,801 DEBUG --- stderr --- 2024-04-11 06:19:48,801 DEBUG 2024-04-11 06:19:48,802 INFO 2024-04-11 06:19:48,802 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-4500/cmd.txt 2024-04-11 06:19:49,655 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 06:19:49,656 DEBUG --- stdout --- 2024-04-11 06:19:49,656 DEBUG 4 /results/orders/authn-users-4500/cmd.txt 2024-04-11 06:19:49,656 DEBUG --- stderr --- 2024-04-11 06:19:49,656 DEBUG 2024-04-11 06:19:49,656 INFO - Download file (size 4k) 2024-04-11 06:19:49,657 INFO 2024-04-11 06:19:49,657 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-11 06:19:49,657 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:49,998 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:49,999 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:49,999 DEBUG --- stdout --- 2024-04-11 06:19:49,999 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:49,999 DEBUG --- stderr --- 2024-04-11 06:19:49,999 DEBUG source : /results/orders/authn-users-4500/cmd.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/cmd.txt 2024-04-11 06:19:49,999 INFO 2024-04-11 06:19:49,999 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-11 06:19:49,999 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 06:19:50,345 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 06:19:50,351 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:50,351 DEBUG --- stdout --- 2024-04-11 06:19:50,351 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 06:19:50,351 DEBUG --- stderr --- 2024-04-11 06:19:50,351 DEBUG 2024-04-11 06:19:50,352 INFO 2024-04-11 06:19:50,352 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-4500/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/cmd.txt 2024-04-11 06:19:50,352 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 06:19:51,164 INFO [loop_until]: OK (rc = 0) 2024-04-11 06:19:51,165 DEBUG --- stdout --- 2024-04-11 06:19:51,165 DEBUG tar: Removing leading `/' from member names 2024-04-11 06:19:51,165 DEBUG --- stderr --- 2024-04-11 06:19:51,165 DEBUG 2024-04-11 06:19:51,165 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/cmd.txt is small (1 lines), printing all lines: 2024-04-11 06:19:51,165 DEBUG ----- output ----- 2024-04-11 06:19:51,166 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-4500 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-11 06:19:51,166 INFO ________________________________________________________________________________ [2024-04-11 06:19:51] authn-users-4500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-11 06:19:51,166 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/rc.txt is small (1 lines), printing all lines: 2024-04-11 06:19:51,166 DEBUG ----- output ----- 2024-04-11 06:19:51,166 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-11 06:19:51,166 INFO ________________________________________________________________________________ [2024-04-11 06:19:51] authn-users-4500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-11 06:19:51,169 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/05_authn-users-4500/stdout.txt is 7096 lines): 2024-04-11 06:19:51,169 DEBUG ----- output ----- 2024-04-11 06:19:51,169 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-11 06:19:51,169 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-11 06:19:51,169 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-11 06:19:51,169 INFO 2024-04-11 06:19:51,169 INFO ================================================================================ 2024-04-11 06:19:51,169 INFO 2024-04-11 07:01:29 5s elapsed 2024-04-11 06:19:51,169 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,170 INFO > Global (OK=1865 KO=0 ) 2024-04-11 06:19:51,170 INFO > userRestLogin (OK=1865 KO=0 ) 2024-04-11 06:19:51,170 INFO 2024-04-11 06:19:51,170 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,170 INFO [- ] 0% 2024-04-11 06:19:51,170 INFO waiting: 16266256 / active: 15 / done: 1865 2024-04-11 06:19:51,170 INFO ================================================================================ 2024-04-11 06:19:51,170 INFO 2024-04-11 06:19:51,170 INFO 2024-04-11 06:19:51,170 INFO ================================================================================ 2024-04-11 06:19:51,170 INFO 2024-04-11 07:01:34 10s elapsed 2024-04-11 06:19:51,170 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,170 INFO > Global (OK=7492 KO=0 ) 2024-04-11 06:19:51,170 INFO > userRestLogin (OK=7492 KO=0 ) 2024-04-11 06:19:51,170 INFO 2024-04-11 06:19:51,170 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,170 INFO [- ] 0% 2024-04-11 06:19:51,170 INFO waiting: 16260628 / active: 16 / done: 7492 2024-04-11 06:19:51,170 INFO ================================================================================ 2024-04-11 06:19:51,170 INFO 2024-04-11 06:19:51,170 INFO 2024-04-11 06:19:51,170 INFO ================================================================================ 2024-04-11 06:19:51,170 INFO 2024-04-11 07:01:39 15s elapsed 2024-04-11 06:19:51,171 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,171 INFO > Global (OK=16846 KO=0 ) 2024-04-11 06:19:51,171 INFO > userRestLogin (OK=16846 KO=0 ) 2024-04-11 06:19:51,171 INFO 2024-04-11 06:19:51,171 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,171 INFO [- ] 0% 2024-04-11 06:19:51,171 INFO waiting: 16251249 / active: 41 / done: 16846 2024-04-11 06:19:51,171 INFO ================================================================================ 2024-04-11 06:19:51,171 INFO 2024-04-11 06:19:51,171 INFO 2024-04-11 06:19:51,171 INFO ================================================================================ 2024-04-11 06:19:51,171 INFO 2024-04-11 07:01:44 20s elapsed 2024-04-11 06:19:51,171 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,171 INFO > Global (OK=29906 KO=0 ) 2024-04-11 06:19:51,171 INFO > userRestLogin (OK=29906 KO=0 ) 2024-04-11 06:19:51,171 INFO 2024-04-11 06:19:51,171 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,171 INFO [- ] 0% 2024-04-11 06:19:51,171 INFO waiting: 16238120 / active: 112 / done: 29904 2024-04-11 06:19:51,171 INFO ================================================================================ 2024-04-11 06:19:51,171 INFO 2024-04-11 06:19:51,171 INFO 2024-04-11 06:19:51,171 INFO ================================================================================ 2024-04-11 06:19:51,171 INFO 2024-04-11 07:01:49 25s elapsed 2024-04-11 06:19:51,171 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,171 INFO > Global (OK=46795 KO=0 ) 2024-04-11 06:19:51,172 INFO > userRestLogin (OK=46795 KO=0 ) 2024-04-11 06:19:51,172 INFO 2024-04-11 06:19:51,172 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,172 INFO [- ] 0% 2024-04-11 06:19:51,172 INFO waiting: 16221245 / active: 98 / done: 46793 2024-04-11 06:19:51,172 INFO ================================================================================ 2024-04-11 06:19:51,172 INFO 2024-04-11 06:19:51,172 INFO 2024-04-11 06:19:51,172 INFO ================================================================================ 2024-04-11 06:19:51,172 INFO 2024-04-11 07:01:54 30s elapsed 2024-04-11 06:19:51,172 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,172 INFO > Global (OK=67291 KO=0 ) 2024-04-11 06:19:51,172 INFO > userRestLogin (OK=67291 KO=0 ) 2024-04-11 06:19:51,172 INFO 2024-04-11 06:19:51,172 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,172 INFO [- ] 0% 2024-04-11 06:19:51,172 INFO waiting: 16200717 / active: 128 / done: 67291 2024-04-11 06:19:51,172 INFO ================================================================================ 2024-04-11 06:19:51,172 INFO 2024-04-11 06:19:51,172 INFO 2024-04-11 06:19:51,172 INFO ================================================================================ 2024-04-11 06:19:51,172 INFO 2024-04-11 07:01:59 35s elapsed 2024-04-11 06:19:51,172 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,172 INFO > Global (OK=88754 KO=0 ) 2024-04-11 06:19:51,172 INFO > userRestLogin (OK=88754 KO=0 ) 2024-04-11 06:19:51,172 INFO 2024-04-11 06:19:51,172 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,172 INFO [- ] 0% 2024-04-11 06:19:51,172 INFO waiting: 16178282 / active: 1102 / done: 88752 2024-04-11 06:19:51,172 INFO ================================================================================ 2024-04-11 06:19:51,173 INFO 2024-04-11 06:19:51,173 INFO 2024-04-11 06:19:51,173 INFO ================================================================================ 2024-04-11 06:19:51,173 INFO 2024-04-11 07:02:04 40s elapsed 2024-04-11 06:19:51,173 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,173 INFO > Global (OK=110130 KO=0 ) 2024-04-11 06:19:51,173 INFO > userRestLogin (OK=110130 KO=0 ) 2024-04-11 06:19:51,173 INFO 2024-04-11 06:19:51,173 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,173 INFO [- ] 0% 2024-04-11 06:19:51,173 INFO waiting: 16155609 / active: 2397 / done: 110130 2024-04-11 06:19:51,173 INFO ================================================================================ 2024-04-11 06:19:51,173 INFO 2024-04-11 06:19:51,173 INFO 2024-04-11 06:19:51,173 INFO [...] 2024-04-11 06:19:51,177 INFO ), but actually found 502 2024-04-11 06:19:51,177 INFO 2024-04-11 06:19:51,177 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,177 INFO [################-- ] 22% 2024-04-11 06:19:51,177 INFO waiting: 12221151 / active: 423195 / done: 3623790 2024-04-11 06:19:51,177 INFO ================================================================================ 2024-04-11 06:19:51,177 INFO 2024-04-11 06:19:51,177 INFO 2024-04-11 06:19:51,177 INFO ================================================================================ 2024-04-11 06:19:51,177 INFO 2024-04-11 07:17:39 975s elapsed 2024-04-11 06:19:51,177 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,177 INFO > Global (OK=3642487 KO=104 ) 2024-04-11 06:19:51,177 INFO > userRestLogin (OK=3642487 KO=104 ) 2024-04-11 06:19:51,177 INFO ---- Errors -------------------------------------------------------------------- 2024-04-11 06:19:51,177 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 104 (100.0%) 2024-04-11 06:19:51,177 INFO ), but actually found 502 2024-04-11 06:19:51,177 INFO 2024-04-11 06:19:51,177 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,177 INFO [################-- ] 22% 2024-04-11 06:19:51,177 INFO waiting: 12208490 / active: 417105 / done: 3642541 2024-04-11 06:19:51,177 INFO ================================================================================ 2024-04-11 06:19:51,178 INFO 2024-04-11 06:19:51,178 INFO 2024-04-11 06:19:51,178 INFO ================================================================================ 2024-04-11 06:19:51,178 INFO 2024-04-11 07:17:44 980s elapsed 2024-04-11 06:19:51,178 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,178 INFO > Global (OK=3659443 KO=104 ) 2024-04-11 06:19:51,178 INFO > userRestLogin (OK=3659443 KO=104 ) 2024-04-11 06:19:51,178 INFO ---- Errors -------------------------------------------------------------------- 2024-04-11 06:19:51,178 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 104 (100.0%) 2024-04-11 06:19:51,178 INFO ), but actually found 502 2024-04-11 06:19:51,178 INFO 2024-04-11 06:19:51,178 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,178 INFO [################-- ] 22% 2024-04-11 06:19:51,178 INFO waiting: 12180975 / active: 427629 / done: 3659532 2024-04-11 06:19:51,178 INFO ================================================================================ 2024-04-11 06:19:51,178 INFO 2024-04-11 06:19:51,178 INFO 2024-04-11 06:19:51,178 INFO ================================================================================ 2024-04-11 06:19:51,178 INFO 2024-04-11 07:17:49 985s elapsed 2024-04-11 06:19:51,178 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,178 INFO > Global (OK=3675372 KO=104 ) 2024-04-11 06:19:51,178 INFO > userRestLogin (OK=3675372 KO=104 ) 2024-04-11 06:19:51,178 INFO ---- Errors -------------------------------------------------------------------- 2024-04-11 06:19:51,179 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 104 (100.0%) 2024-04-11 06:19:51,179 INFO ), but actually found 502 2024-04-11 06:19:51,179 INFO 2024-04-11 06:19:51,179 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,179 INFO [################--- ] 22% 2024-04-11 06:19:51,179 INFO waiting: 12141400 / active: 451278 / done: 3675458 2024-04-11 06:19:51,179 INFO ================================================================================ 2024-04-11 06:19:51,179 INFO 2024-04-11 06:19:51,179 INFO 2024-04-11 06:19:51,179 INFO ================================================================================ 2024-04-11 06:19:51,179 INFO 2024-04-11 07:17:54 990s elapsed 2024-04-11 06:19:51,179 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,179 INFO > Global (OK=3694220 KO=104 ) 2024-04-11 06:19:51,179 INFO > userRestLogin (OK=3694220 KO=104 ) 2024-04-11 06:19:51,179 INFO ---- Errors -------------------------------------------------------------------- 2024-04-11 06:19:51,179 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 104 (100.0%) 2024-04-11 06:19:51,179 INFO ), but actually found 502 2024-04-11 06:19:51,179 INFO 2024-04-11 06:19:51,179 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,179 INFO [################--- ] 22% 2024-04-11 06:19:51,179 INFO waiting: 12118118 / active: 455694 / done: 3694324 2024-04-11 06:19:51,179 INFO ================================================================================ 2024-04-11 06:19:51,179 INFO 2024-04-11 06:19:51,180 INFO 2024-04-11 06:19:51,180 INFO ================================================================================ 2024-04-11 06:19:51,180 INFO 2024-04-11 07:17:59 995s elapsed 2024-04-11 06:19:51,180 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 06:19:51,180 INFO > Global (OK=3706574 KO=104 ) 2024-04-11 06:19:51,180 INFO > userRestLogin (OK=3706574 KO=104 ) 2024-04-11 06:19:51,180 INFO ---- Errors -------------------------------------------------------------------- 2024-04-11 06:19:51,180 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 104 (100.0%) 2024-04-11 06:19:51,180 INFO ), but actually found 502 2024-04-11 06:19:51,180 INFO 2024-04-11 06:19:51,180 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 06:19:51,180 INFO [################--- ] 22% 2024-04-11 06:19:51,180 INFO waiting: 12095935 / active: 465523 / done: 3706678 2024-04-11 06:19:51,180 INFO ================================================================================ 2024-04-11 06:19:51,180 INFO 2024-04-11 06:19:51,180 INFO 2024-04-11 06:19:51,180 INFO ---------------------- 2024-04-11 06:19:51,180 INFO [11/Apr/2024 07:18:01] Create tar file authn-users-4500.tar.gz 2024-04-11 06:19:51,180 INFO ---------------------- 2024-04-11 06:19:51,180 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-11 06:19:51,180 INFO RESULT_FOLDER=authn-users-4500 2024-04-11 06:19:51,180 INFO SIMULATION_PATH=authn-users-4500/loadtest-20240411060123629 2024-04-11 06:19:51,180 INFO PWD=/results/gatling/authn-users-4500/loadtest-20240411060123629 2024-04-11 06:19:51,180 INFO [cmd] tar -czvf /results/gatling//authn-users-4500.tar.gz --exclude=simulation.log * 2024-04-11 06:19:51,180 INFO /results/gatling 2024-04-11 06:19:51,181 INFO 2024-04-11 06:19:51,181 INFO ---------------------- 2024-04-11 06:19:51,181 INFO [11/Apr/2024 07:18:01] Update /results/gatling//latest 2024-04-11 06:19:51,181 INFO ---------------------- 2024-04-11 06:19:51,181 INFO 2024-04-11 06:19:51,181 INFO ---------------------- 2024-04-11 06:19:51,181 INFO [11/Apr/2024 07:18:01] Update /results/gatling//latest.tar.gz 2024-04-11 06:19:51,181 INFO ---------------------- 2024-04-11 06:19:51,181 INFO ________________________________________________________________________________ [2024-04-11 06:19:51] authn-users-4500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-11 06:19:51,181 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-4500/stderr.txt is small (0 lines), printing all lines: 2024-04-11 06:19:51,182 DEBUG ----- output ----- 2024-04-11 06:19:51,182 DEBUG 2024-04-11 06:19:51,182 INFO ________________________________________________________________________________ [2024-04-11 06:19:51] authn-users-4500 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-11 06:19:51] authn-users-4500 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-11 06:19:51] authn-users-4500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped