--Task-- name: authn-users-3000 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-2000'] preceding_task: authn-users-2000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 3000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-11 03:55:43] authn-users-3000 pre : N/A ________________________________________________________________________________ [2024-04-11 03:55:43] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-11 03:55:43] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-3000/stdout.txt Pod log : /results/orders/authn-users-3000 2024-04-11 03:55:43,311 INFO 2024-04-11 03:55:43,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 03:55:43,311 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:43,620 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:43,620 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:43,620 DEBUG --- stdout --- 2024-04-11 03:55:43,620 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:43,620 DEBUG --- stderr --- 2024-04-11 03:55:43,620 DEBUG ________________________________________________________________________________ [2024-04-11 03:55:43] authn-users-3000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-11 03:55:43,621 INFO 2024-04-11 03:55:43,621 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 03:55:43,621 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:43,925 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:43,925 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:43,925 DEBUG --- stdout --- 2024-04-11 03:55:43,925 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:43,925 DEBUG --- stderr --- 2024-04-11 03:55:43,925 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-3000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-11 03:55:43] authn-users-3000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-11 03:55:43,926 INFO 2024-04-11 03:55:43,926 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 03:55:43,926 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:44,238 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:44,238 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:44,238 DEBUG --- stdout --- 2024-04-11 03:55:44,238 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:44,238 DEBUG --- stderr --- 2024-04-11 03:55:44,238 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/order.json { "name": "authn-users-3000", "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-3000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-3000", "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=3000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-11 03:55:44] authn-users-3000 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 03:55:44,240 INFO 2024-04-11 03:55:44,240 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-11 03:55:44,240 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-11 03:55:44,479 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 03:55:44,479 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:44,479 DEBUG --- stdout --- 2024-04-11 03:55:44,479 DEBUG WAITING 2024-04-11 03:55:44,479 DEBUG --- stderr --- 2024-04-11 03:55:44,479 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-11 03:55:44] authn-users-3000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-11 03:55:44,480 INFO 2024-04-11 03:55:44,480 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 03:55:44,480 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:44,804 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:44,804 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:44,804 DEBUG --- stdout --- 2024-04-11 03:55:44,804 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:44,805 DEBUG --- stderr --- 2024-04-11 03:55:44,805 DEBUG 2024-04-11 03:55:44,805 INFO 2024-04-11 03:55:44,805 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/order.json overseer-0-78845f4f8c-v2r2q:/results/orders/order.json 2024-04-11 03:55:44,805 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 03:55:46,198 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:46,198 DEBUG --- stdout --- 2024-04-11 03:55:46,198 DEBUG 2024-04-11 03:55:46,198 DEBUG --- stderr --- 2024-04-11 03:55:46,198 DEBUG Order has been successfully forwarded to overseer [2024-04-11 03:55:46] - INFO: Check if Gatling simulation started successfully 2024-04-11 03:55:46,199 INFO 2024-04-11 03:55:46,199 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 03:55:46,199 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:46,509 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:46,510 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:46,510 DEBUG --- stdout --- 2024-04-11 03:55:46,510 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:46,510 DEBUG --- stderr --- 2024-04-11 03:55:46,510 DEBUG 2024-04-11 03:55:46,510 INFO 2024-04-11 03:55:46,510 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-3000/stdout.txt | grep "started..." 2024-04-11 03:55:46,510 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-11 03:55:47,289 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-11 03:55:53,066 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-11 03:55:58,841 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-11 03:55:58,841 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:58,841 DEBUG --- stdout --- 2024-04-11 03:55:58,841 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 04:55:58 5s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=1254 KO=0 ) > userRestLogin (OK=1254 KO=0 ) ---- am.AMAuthN ---------------------------------------------------------------- [- ] 0% waiting: 10842234 / active: 5 / done: 1254 ================================================================================ 2024-04-11 03:55:58,841 DEBUG --- stderr --- 2024-04-11 03:55:58,841 DEBUG ________________________________________________________________________________ [2024-04-11 03:55:58] authn-users-3000 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 09s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 10s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-11 04:58:09] authn-users-3000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-11 04:58:09,667 INFO 2024-04-11 04:58:09,667 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 04:58:09,667 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:10,010 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:10,011 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:10,011 DEBUG --- stdout --- 2024-04-11 04:58:10,011 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:10,011 DEBUG --- stderr --- 2024-04-11 04:58:10,011 DEBUG 2024-04-11 04:58:10,011 INFO 2024-04-11 04:58:10,011 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 04:58:10,011 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 04:58:10,831 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:10,831 DEBUG --- stdout --- 2024-04-11 04:58:10,831 DEBUG 2024-04-11 04:58:10,831 DEBUG --- stderr --- 2024-04-11 04:58:10,831 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-11 04:58:10] authn-users-3000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-11 04:58:10] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/order.json 2024-04-11 04:58:10,832 INFO 2024-04-11 04:58:10,832 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 04:58:10,832 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:11,150 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:11,151 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:11,151 DEBUG --- stdout --- 2024-04-11 04:58:11,151 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:11,151 DEBUG --- stderr --- 2024-04-11 04:58:11,151 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 04:58:11,151 INFO 2024-04-11 04:58:11,151 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-11 04:58:11,151 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:11,468 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:11,469 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:11,469 DEBUG --- stdout --- 2024-04-11 04:58:11,469 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:11,469 DEBUG --- stderr --- 2024-04-11 04:58:11,469 DEBUG 2024-04-11 04:58:11,470 INFO 2024-04-11 04:58:11,470 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-3000/rc.txt" ]; then echo "/results/orders/authn-users-3000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/rc.txt file found" 2024-04-11 04:58:11,470 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 04:58:12,315 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 04:58:12,315 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:12,315 DEBUG --- stdout --- 2024-04-11 04:58:12,315 DEBUG /results/orders/authn-users-3000/rc.txt file found 2024-04-11 04:58:12,315 DEBUG --- stderr --- 2024-04-11 04:58:12,315 DEBUG 2024-04-11 04:58:12,316 INFO 2024-04-11 04:58:12,316 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 04:58:12,316 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:12,630 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:12,631 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:12,632 DEBUG --- stdout --- 2024-04-11 04:58:12,632 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:12,632 DEBUG --- stderr --- 2024-04-11 04:58:12,632 DEBUG 2024-04-11 04:58:12,632 INFO 2024-04-11 04:58:12,632 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-3000/rc.txt 2024-04-11 04:58:13,466 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 04:58:13,466 DEBUG --- stdout --- 2024-04-11 04:58:13,466 DEBUG 4 /results/orders/authn-users-3000/rc.txt 2024-04-11 04:58:13,466 DEBUG --- stderr --- 2024-04-11 04:58:13,466 DEBUG 2024-04-11 04:58:13,466 INFO - Download file (size 4k) 2024-04-11 04:58:13,466 INFO 2024-04-11 04:58:13,466 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 04:58:13,467 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:13,787 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:13,788 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:13,788 DEBUG --- stdout --- 2024-04-11 04:58:13,788 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:13,788 DEBUG --- stderr --- 2024-04-11 04:58:13,788 DEBUG source : /results/orders/authn-users-3000/rc.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/rc.txt 2024-04-11 04:58:13,788 INFO 2024-04-11 04:58:13,788 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 04:58:13,788 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:14,098 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:14,099 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:14,099 DEBUG --- stdout --- 2024-04-11 04:58:14,099 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:14,099 DEBUG --- stderr --- 2024-04-11 04:58:14,099 DEBUG 2024-04-11 04:58:14,099 INFO 2024-04-11 04:58:14,099 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-3000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/rc.txt 2024-04-11 04:58:14,099 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 04:58:14,854 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:14,854 DEBUG --- stdout --- 2024-04-11 04:58:14,854 DEBUG tar: Removing leading `/' from member names 2024-04-11 04:58:14,854 DEBUG --- stderr --- 2024-04-11 04:58:14,854 DEBUG 2024-04-11 04:58:14,854 INFO 2024-04-11 04:58:14,855 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 04:58:14,855 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:15,162 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:15,163 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:15,163 DEBUG --- stdout --- 2024-04-11 04:58:15,163 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:15,163 DEBUG --- stderr --- 2024-04-11 04:58:15,163 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 04:58:15,163 INFO 2024-04-11 04:58:15,163 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 04:58:15,163 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:15,488 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:15,488 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:15,488 DEBUG --- stdout --- 2024-04-11 04:58:15,488 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:15,488 DEBUG --- stderr --- 2024-04-11 04:58:15,488 DEBUG 2024-04-11 04:58:15,489 INFO 2024-04-11 04:58:15,489 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-3000/stderr.txt" ]; then echo "/results/orders/authn-users-3000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/stderr.txt file found" 2024-04-11 04:58:15,489 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 04:58:16,345 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 04:58:16,345 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:16,345 DEBUG --- stdout --- 2024-04-11 04:58:16,345 DEBUG /results/orders/authn-users-3000/stderr.txt file found 2024-04-11 04:58:16,345 DEBUG --- stderr --- 2024-04-11 04:58:16,345 DEBUG 2024-04-11 04:58:16,346 INFO 2024-04-11 04:58:16,346 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 04:58:16,346 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:16,654 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:16,655 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:16,655 DEBUG --- stdout --- 2024-04-11 04:58:16,655 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:16,655 DEBUG --- stderr --- 2024-04-11 04:58:16,655 DEBUG 2024-04-11 04:58:16,655 INFO 2024-04-11 04:58:16,655 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-3000/stderr.txt 2024-04-11 04:58:17,495 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 04:58:17,495 DEBUG --- stdout --- 2024-04-11 04:58:17,495 DEBUG 0 /results/orders/authn-users-3000/stderr.txt 2024-04-11 04:58:17,495 DEBUG --- stderr --- 2024-04-11 04:58:17,495 DEBUG 2024-04-11 04:58:17,495 INFO - Download file (size 0k) 2024-04-11 04:58:17,495 INFO 2024-04-11 04:58:17,495 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 04:58:17,496 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:17,813 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:17,814 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:17,814 DEBUG --- stdout --- 2024-04-11 04:58:17,814 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:17,814 DEBUG --- stderr --- 2024-04-11 04:58:17,814 DEBUG source : /results/orders/authn-users-3000/stderr.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/stderr.txt 2024-04-11 04:58:17,815 INFO 2024-04-11 04:58:17,815 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 04:58:17,815 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:18,129 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:18,129 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:18,129 DEBUG --- stdout --- 2024-04-11 04:58:18,130 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:18,130 DEBUG --- stderr --- 2024-04-11 04:58:18,130 DEBUG 2024-04-11 04:58:18,130 INFO 2024-04-11 04:58:18,130 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-3000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/stderr.txt 2024-04-11 04:58:18,130 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 04:58:18,889 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:18,889 DEBUG --- stdout --- 2024-04-11 04:58:18,889 DEBUG tar: Removing leading `/' from member names 2024-04-11 04:58:18,889 DEBUG --- stderr --- 2024-04-11 04:58:18,889 DEBUG 2024-04-11 04:58:18,890 INFO 2024-04-11 04:58:18,890 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 04:58:18,890 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:19,234 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:19,234 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:19,234 DEBUG --- stdout --- 2024-04-11 04:58:19,235 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:19,235 DEBUG --- stderr --- 2024-04-11 04:58:19,235 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 04:58:19,235 INFO 2024-04-11 04:58:19,235 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-11 04:58:19,235 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:19,568 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:19,568 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:19,568 DEBUG --- stdout --- 2024-04-11 04:58:19,568 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:19,569 DEBUG --- stderr --- 2024-04-11 04:58:19,569 DEBUG 2024-04-11 04:58:19,569 INFO 2024-04-11 04:58:19,569 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-3000/stdout.txt" ]; then echo "/results/orders/authn-users-3000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/stdout.txt file found" 2024-04-11 04:58:19,569 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 04:58:20,473 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 04:58:20,473 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:20,473 DEBUG --- stdout --- 2024-04-11 04:58:20,473 DEBUG /results/orders/authn-users-3000/stdout.txt file found 2024-04-11 04:58:20,473 DEBUG --- stderr --- 2024-04-11 04:58:20,473 DEBUG 2024-04-11 04:58:20,473 INFO 2024-04-11 04:58:20,473 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 04:58:20,473 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:20,785 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:20,786 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:20,786 DEBUG --- stdout --- 2024-04-11 04:58:20,786 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:20,786 DEBUG --- stderr --- 2024-04-11 04:58:20,786 DEBUG 2024-04-11 04:58:20,786 INFO 2024-04-11 04:58:20,786 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-3000/stdout.txt 2024-04-11 04:58:21,623 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 04:58:21,623 DEBUG --- stdout --- 2024-04-11 04:58:21,623 DEBUG 508 /results/orders/authn-users-3000/stdout.txt 2024-04-11 04:58:21,623 DEBUG --- stderr --- 2024-04-11 04:58:21,623 DEBUG 2024-04-11 04:58:21,623 INFO - Download file (size 508k) 2024-04-11 04:58:21,623 INFO 2024-04-11 04:58:21,624 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 04:58:21,624 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:21,939 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:21,939 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:21,939 DEBUG --- stdout --- 2024-04-11 04:58:21,939 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:21,939 DEBUG --- stderr --- 2024-04-11 04:58:21,939 DEBUG source : /results/orders/authn-users-3000/stdout.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/stdout.txt 2024-04-11 04:58:21,939 INFO 2024-04-11 04:58:21,939 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 04:58:21,939 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:22,252 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:22,253 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:22,253 DEBUG --- stdout --- 2024-04-11 04:58:22,253 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:22,253 DEBUG --- stderr --- 2024-04-11 04:58:22,253 DEBUG 2024-04-11 04:58:22,253 INFO 2024-04-11 04:58:22,253 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-3000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/stdout.txt 2024-04-11 04:58:22,253 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 04:58:23,358 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:23,358 DEBUG --- stdout --- 2024-04-11 04:58:23,358 DEBUG tar: Removing leading `/' from member names 2024-04-11 04:58:23,358 DEBUG --- stderr --- 2024-04-11 04:58:23,358 DEBUG ________________________________________________________________________________ [2024-04-11 04:58:23] authn-users-3000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-11 04:58:23,359 INFO 2024-04-11 04:58:23,359 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 04:58:23,359 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:23,678 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:23,678 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:23,678 DEBUG --- stdout --- 2024-04-11 04:58:23,678 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:23,679 DEBUG --- stderr --- 2024-04-11 04:58:23,679 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 04:58:23,679 INFO 2024-04-11 04:58:23,679 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 04:58:23,679 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:23,995 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:23,995 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:23,995 DEBUG --- stdout --- 2024-04-11 04:58:23,995 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:23,995 DEBUG --- stderr --- 2024-04-11 04:58:23,995 DEBUG 2024-04-11 04:58:23,996 INFO 2024-04-11 04:58:23,996 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-3000/cmd.txt" ]; then echo "/results/orders/authn-users-3000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/cmd.txt file found" 2024-04-11 04:58:23,998 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 04:58:24,843 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 04:58:24,843 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:24,843 DEBUG --- stdout --- 2024-04-11 04:58:24,844 DEBUG /results/orders/authn-users-3000/cmd.txt file found 2024-04-11 04:58:24,844 DEBUG --- stderr --- 2024-04-11 04:58:24,844 DEBUG 2024-04-11 04:58:24,844 INFO 2024-04-11 04:58:24,844 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 04:58:24,844 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:25,188 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:25,188 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:25,188 DEBUG --- stdout --- 2024-04-11 04:58:25,188 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:25,188 DEBUG --- stderr --- 2024-04-11 04:58:25,188 DEBUG 2024-04-11 04:58:25,188 INFO 2024-04-11 04:58:25,188 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-3000/cmd.txt 2024-04-11 04:58:26,029 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 04:58:26,029 DEBUG --- stdout --- 2024-04-11 04:58:26,029 DEBUG 4 /results/orders/authn-users-3000/cmd.txt 2024-04-11 04:58:26,029 DEBUG --- stderr --- 2024-04-11 04:58:26,029 DEBUG 2024-04-11 04:58:26,029 INFO - Download file (size 4k) 2024-04-11 04:58:26,030 INFO 2024-04-11 04:58:26,030 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 04:58:26,030 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:26,352 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:26,352 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:26,352 DEBUG --- stdout --- 2024-04-11 04:58:26,352 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:26,352 DEBUG --- stderr --- 2024-04-11 04:58:26,352 DEBUG source : /results/orders/authn-users-3000/cmd.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/cmd.txt 2024-04-11 04:58:26,353 INFO 2024-04-11 04:58:26,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-11 04:58:26,353 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 04:58:26,666 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 04:58:26,666 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:26,666 DEBUG --- stdout --- 2024-04-11 04:58:26,666 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 04:58:26,667 DEBUG --- stderr --- 2024-04-11 04:58:26,667 DEBUG 2024-04-11 04:58:26,667 INFO 2024-04-11 04:58:26,667 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-3000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/cmd.txt 2024-04-11 04:58:26,667 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 04:58:27,425 INFO [loop_until]: OK (rc = 0) 2024-04-11 04:58:27,425 DEBUG --- stdout --- 2024-04-11 04:58:27,425 DEBUG tar: Removing leading `/' from member names 2024-04-11 04:58:27,426 DEBUG --- stderr --- 2024-04-11 04:58:27,426 DEBUG 2024-04-11 04:58:27,426 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/cmd.txt is small (1 lines), printing all lines: 2024-04-11 04:58:27,426 DEBUG ----- output ----- 2024-04-11 04:58:27,426 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-3000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-11 04:58:27,426 INFO ________________________________________________________________________________ [2024-04-11 04:58:27] authn-users-3000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-11 04:58:27,426 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/rc.txt is small (1 lines), printing all lines: 2024-04-11 04:58:27,426 DEBUG ----- output ----- 2024-04-11 04:58:27,426 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-11 04:58:27,427 INFO ________________________________________________________________________________ [2024-04-11 04:58:27] authn-users-3000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-11 04:58:27,428 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/stdout.txt is 8734 lines): 2024-04-11 04:58:27,428 DEBUG ----- output ----- 2024-04-11 04:58:27,428 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-11 04:58:27,428 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-11 04:58:27,428 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-11 04:58:27,428 INFO 2024-04-11 04:58:27,428 INFO ================================================================================ 2024-04-11 04:58:27,428 INFO 2024-04-11 04:55:58 5s elapsed 2024-04-11 04:58:27,428 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,428 INFO > Global (OK=1254 KO=0 ) 2024-04-11 04:58:27,428 INFO > userRestLogin (OK=1254 KO=0 ) 2024-04-11 04:58:27,428 INFO 2024-04-11 04:58:27,428 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,428 INFO [- ] 0% 2024-04-11 04:58:27,428 INFO waiting: 10842234 / active: 5 / done: 1254 2024-04-11 04:58:27,428 INFO ================================================================================ 2024-04-11 04:58:27,428 INFO 2024-04-11 04:58:27,428 INFO 2024-04-11 04:58:27,428 INFO ================================================================================ 2024-04-11 04:58:27,428 INFO 2024-04-11 04:56:03 10s elapsed 2024-04-11 04:58:27,429 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,429 INFO > Global (OK=4997 KO=0 ) 2024-04-11 04:58:27,429 INFO > userRestLogin (OK=4997 KO=0 ) 2024-04-11 04:58:27,429 INFO 2024-04-11 04:58:27,429 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,429 INFO [- ] 0% 2024-04-11 04:58:27,429 INFO waiting: 10838485 / active: 11 / done: 4997 2024-04-11 04:58:27,429 INFO ================================================================================ 2024-04-11 04:58:27,429 INFO 2024-04-11 04:58:27,429 INFO 2024-04-11 04:58:27,429 INFO ================================================================================ 2024-04-11 04:58:27,429 INFO 2024-04-11 04:56:08 15s elapsed 2024-04-11 04:58:27,429 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,429 INFO > Global (OK=11243 KO=0 ) 2024-04-11 04:58:27,429 INFO > userRestLogin (OK=11243 KO=0 ) 2024-04-11 04:58:27,429 INFO 2024-04-11 04:58:27,429 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,429 INFO [- ] 0% 2024-04-11 04:58:27,429 INFO waiting: 10832233 / active: 17 / done: 11243 2024-04-11 04:58:27,429 INFO ================================================================================ 2024-04-11 04:58:27,429 INFO 2024-04-11 04:58:27,429 INFO 2024-04-11 04:58:27,429 INFO ================================================================================ 2024-04-11 04:58:27,429 INFO 2024-04-11 04:56:13 20s elapsed 2024-04-11 04:58:27,429 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,429 INFO > Global (OK=19993 KO=0 ) 2024-04-11 04:58:27,429 INFO > userRestLogin (OK=19993 KO=0 ) 2024-04-11 04:58:27,429 INFO 2024-04-11 04:58:27,429 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,429 INFO [- ] 0% 2024-04-11 04:58:27,429 INFO waiting: 10823478 / active: 22 / done: 19993 2024-04-11 04:58:27,429 INFO ================================================================================ 2024-04-11 04:58:27,429 INFO 2024-04-11 04:58:27,429 INFO 2024-04-11 04:58:27,429 INFO ================================================================================ 2024-04-11 04:58:27,429 INFO 2024-04-11 04:56:18 25s elapsed 2024-04-11 04:58:27,429 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,429 INFO > Global (OK=31238 KO=0 ) 2024-04-11 04:58:27,429 INFO > userRestLogin (OK=31238 KO=0 ) 2024-04-11 04:58:27,429 INFO 2024-04-11 04:58:27,430 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,430 INFO [- ] 0% 2024-04-11 04:58:27,430 INFO waiting: 10812227 / active: 28 / done: 31238 2024-04-11 04:58:27,430 INFO ================================================================================ 2024-04-11 04:58:27,430 INFO 2024-04-11 04:58:27,430 INFO 2024-04-11 04:58:27,430 INFO ================================================================================ 2024-04-11 04:58:27,430 INFO 2024-04-11 04:56:23 30s elapsed 2024-04-11 04:58:27,430 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,430 INFO > Global (OK=44922 KO=0 ) 2024-04-11 04:58:27,430 INFO > userRestLogin (OK=44922 KO=0 ) 2024-04-11 04:58:27,430 INFO 2024-04-11 04:58:27,430 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,430 INFO [- ] 0% 2024-04-11 04:58:27,430 INFO waiting: 10798475 / active: 96 / done: 44922 2024-04-11 04:58:27,430 INFO ================================================================================ 2024-04-11 04:58:27,430 INFO 2024-04-11 04:58:27,430 INFO 2024-04-11 04:58:27,430 INFO ================================================================================ 2024-04-11 04:58:27,430 INFO 2024-04-11 04:56:28 35s elapsed 2024-04-11 04:58:27,430 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,430 INFO > Global (OK=60090 KO=0 ) 2024-04-11 04:58:27,430 INFO > userRestLogin (OK=60090 KO=0 ) 2024-04-11 04:58:27,430 INFO 2024-04-11 04:58:27,430 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,430 INFO [- ] 0% 2024-04-11 04:58:27,430 INFO waiting: 10783365 / active: 38 / done: 60090 2024-04-11 04:58:27,430 INFO ================================================================================ 2024-04-11 04:58:27,430 INFO 2024-04-11 04:58:27,430 INFO 2024-04-11 04:58:27,430 INFO ================================================================================ 2024-04-11 04:58:27,430 INFO 2024-04-11 04:56:33 40s elapsed 2024-04-11 04:58:27,430 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,430 INFO > Global (OK=75095 KO=0 ) 2024-04-11 04:58:27,430 INFO > userRestLogin (OK=75095 KO=0 ) 2024-04-11 04:58:27,430 INFO 2024-04-11 04:58:27,431 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,431 INFO [- ] 0% 2024-04-11 04:58:27,431 INFO waiting: 10768369 / active: 29 / done: 75095 2024-04-11 04:58:27,431 INFO ================================================================================ 2024-04-11 04:58:27,431 INFO 2024-04-11 04:58:27,431 INFO 2024-04-11 04:58:27,431 INFO [...] 2024-04-11 04:58:27,433 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,433 INFO > Global (OK=10753728 KO=0 ) 2024-04-11 04:58:27,433 INFO > userRestLogin (OK=10753728 KO=0 ) 2024-04-11 04:58:27,433 INFO 2024-04-11 04:58:27,433 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,433 INFO [#########################################################################-] 99% 2024-04-11 04:58:27,433 INFO waiting: 89722 / active: 43 / done: 10753728 2024-04-11 04:58:27,433 INFO ================================================================================ 2024-04-11 04:58:27,433 INFO 2024-04-11 04:58:27,433 INFO 2024-04-11 04:58:27,433 INFO ================================================================================ 2024-04-11 04:58:27,433 INFO 2024-04-11 05:55:58 3605s elapsed 2024-04-11 04:58:27,433 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,433 INFO > Global (OK=10768683 KO=0 ) 2024-04-11 04:58:27,433 INFO > userRestLogin (OK=10768683 KO=0 ) 2024-04-11 04:58:27,433 INFO 2024-04-11 04:58:27,433 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,433 INFO [#########################################################################-] 99% 2024-04-11 04:58:27,433 INFO waiting: 74765 / active: 46 / done: 10768682 2024-04-11 04:58:27,433 INFO ================================================================================ 2024-04-11 04:58:27,433 INFO 2024-04-11 04:58:27,433 INFO 2024-04-11 04:58:27,433 INFO ================================================================================ 2024-04-11 04:58:27,433 INFO 2024-04-11 05:56:03 3610s elapsed 2024-04-11 04:58:27,434 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,434 INFO > Global (OK=10783434 KO=0 ) 2024-04-11 04:58:27,434 INFO > userRestLogin (OK=10783434 KO=0 ) 2024-04-11 04:58:27,434 INFO 2024-04-11 04:58:27,434 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,434 INFO [#########################################################################-] 99% 2024-04-11 04:58:27,434 INFO waiting: 59996 / active: 63 / done: 10783434 2024-04-11 04:58:27,434 INFO ================================================================================ 2024-04-11 04:58:27,434 INFO 2024-04-11 04:58:27,434 INFO 2024-04-11 04:58:27,434 INFO ================================================================================ 2024-04-11 04:58:27,434 INFO 2024-04-11 05:56:08 3615s elapsed 2024-04-11 04:58:27,434 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,434 INFO > Global (OK=10798523 KO=0 ) 2024-04-11 04:58:27,434 INFO > userRestLogin (OK=10798523 KO=0 ) 2024-04-11 04:58:27,434 INFO 2024-04-11 04:58:27,434 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,434 INFO [#########################################################################-] 99% 2024-04-11 04:58:27,434 INFO waiting: 44922 / active: 48 / done: 10798523 2024-04-11 04:58:27,434 INFO ================================================================================ 2024-04-11 04:58:27,434 INFO 2024-04-11 04:58:27,434 INFO 2024-04-11 04:58:27,434 INFO ================================================================================ 2024-04-11 04:58:27,434 INFO 2024-04-11 05:56:13 3620s elapsed 2024-04-11 04:58:27,434 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,434 INFO > Global (OK=10813488 KO=0 ) 2024-04-11 04:58:27,434 INFO > userRestLogin (OK=10813488 KO=0 ) 2024-04-11 04:58:27,434 INFO 2024-04-11 04:58:27,434 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,434 INFO [#########################################################################-] 99% 2024-04-11 04:58:27,434 INFO waiting: 29954 / active: 51 / done: 10813488 2024-04-11 04:58:27,434 INFO ================================================================================ 2024-04-11 04:58:27,434 INFO 2024-04-11 04:58:27,434 INFO 2024-04-11 04:58:27,434 INFO ================================================================================ 2024-04-11 04:58:27,434 INFO 2024-04-11 05:56:18 3625s elapsed 2024-04-11 04:58:27,434 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,434 INFO > Global (OK=10828276 KO=0 ) 2024-04-11 04:58:27,435 INFO > userRestLogin (OK=10828276 KO=0 ) 2024-04-11 04:58:27,435 INFO 2024-04-11 04:58:27,435 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,435 INFO [#########################################################################-] 99% 2024-04-11 04:58:27,435 INFO waiting: 15129 / active: 89 / done: 10828275 2024-04-11 04:58:27,435 INFO ================================================================================ 2024-04-11 04:58:27,435 INFO 2024-04-11 04:58:27,435 INFO 2024-04-11 04:58:27,435 INFO ================================================================================ 2024-04-11 04:58:27,435 INFO 2024-04-11 05:56:23 3630s elapsed 2024-04-11 04:58:27,435 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 04:58:27,435 INFO > Global (OK=10843493 KO=0 ) 2024-04-11 04:58:27,435 INFO > userRestLogin (OK=10843493 KO=0 ) 2024-04-11 04:58:27,435 INFO 2024-04-11 04:58:27,435 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 04:58:27,435 INFO [##########################################################################]100% 2024-04-11 04:58:27,435 INFO waiting: 0 / active: 0 / done: 10843493 2024-04-11 04:58:27,435 INFO ================================================================================ 2024-04-11 04:58:27,435 INFO 2024-04-11 04:58:27,435 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-11 04:58:27,435 INFO 2024-04-11 04:58:27,435 INFO ---------------------- 2024-04-11 04:58:27,435 INFO [11/Apr/2024 05:56:23] Create tar file authn-users-3000.tar.gz 2024-04-11 04:58:27,435 INFO ---------------------- 2024-04-11 04:58:27,435 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-11 04:58:27,435 INFO RESULT_FOLDER=authn-users-3000 2024-04-11 04:58:27,435 INFO SIMULATION_PATH=authn-users-3000/loadtest-20240411035552556 2024-04-11 04:58:27,435 INFO PWD=/results/gatling/authn-users-3000/loadtest-20240411035552556 2024-04-11 04:58:27,435 INFO [cmd] tar -czvf /results/gatling//authn-users-3000.tar.gz --exclude=simulation.log * 2024-04-11 04:58:27,435 INFO /results/gatling 2024-04-11 04:58:27,435 INFO 2024-04-11 04:58:27,435 INFO ---------------------- 2024-04-11 04:58:27,435 INFO [11/Apr/2024 05:56:23] Update /results/gatling//latest 2024-04-11 04:58:27,435 INFO ---------------------- 2024-04-11 04:58:27,435 INFO 2024-04-11 04:58:27,435 INFO ---------------------- 2024-04-11 04:58:27,435 INFO [11/Apr/2024 05:56:23] Update /results/gatling//latest.tar.gz 2024-04-11 04:58:27,435 INFO ---------------------- 2024-04-11 04:58:27,435 INFO ________________________________________________________________________________ [2024-04-11 04:58:27] authn-users-3000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-11 04:58:27,436 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-3000/stderr.txt is small (0 lines), printing all lines: 2024-04-11 04:58:27,436 DEBUG ----- output ----- 2024-04-11 04:58:27,436 DEBUG 2024-04-11 04:58:27,436 INFO ________________________________________________________________________________ [2024-04-11 04:58:27] authn-users-3000 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 04:58:27] authn-users-3000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-11 04:58:27] authn-users-3000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped