--Task-- name: authn-users-7000 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-6000'] preceding_task: authn-users-6000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 7000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-20 00:16:19] authn-users-7000 pre : N/A ________________________________________________________________________________ [2024-04-20 00:16:19] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-20 00:16:19] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-7000/stdout.txt Pod log : /results/orders/authn-users-7000 2024-04-20 00:16:19,418 INFO 2024-04-20 00:16:19,418 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-20 00:16:19,418 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:19,755 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:19,756 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:19,756 DEBUG --- stdout --- 2024-04-20 00:16:19,756 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:19,756 DEBUG --- stderr --- 2024-04-20 00:16:19,756 DEBUG ________________________________________________________________________________ [2024-04-20 00:16:19] authn-users-7000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-20 00:16:19,757 INFO 2024-04-20 00:16:19,757 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-20 00:16:19,758 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:20,115 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:20,115 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:20,115 DEBUG --- stdout --- 2024-04-20 00:16:20,115 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:20,115 DEBUG --- stderr --- 2024-04-20 00:16:20,115 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-7000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-20 00:16:20] authn-users-7000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-20 00:16:20,116 INFO 2024-04-20 00:16:20,116 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-20 00:16:20,116 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:20,432 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:20,432 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:20,432 DEBUG --- stdout --- 2024-04-20 00:16:20,432 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:20,432 DEBUG --- stderr --- 2024-04-20 00:16:20,432 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/order.json { "name": "authn-users-7000", "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-7000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-7000", "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=7000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-20 00:16:20] authn-users-7000 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-20 00:16:20,434 INFO 2024-04-20 00:16:20,434 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-20 00:16:20,434 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-20 00:16:20,674 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 00:16:20,674 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:20,674 DEBUG --- stdout --- 2024-04-20 00:16:20,674 DEBUG WAITING 2024-04-20 00:16:20,674 DEBUG --- stderr --- 2024-04-20 00:16:20,675 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 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-20 00:16:20] authn-users-7000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-20 00:16:20,675 INFO 2024-04-20 00:16:20,675 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-20 00:16:20,675 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:20,995 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:20,995 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:20,995 DEBUG --- stdout --- 2024-04-20 00:16:20,995 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:20,995 DEBUG --- stderr --- 2024-04-20 00:16:20,995 DEBUG 2024-04-20 00:16:20,995 INFO 2024-04-20 00:16:20,995 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/04_authn-users-7000/order.json overseer-0-5c67bf94db-77mh9:/results/orders/order.json 2024-04-20 00:16:20,996 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 00:16:22,382 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:22,382 DEBUG --- stdout --- 2024-04-20 00:16:22,382 DEBUG 2024-04-20 00:16:22,382 DEBUG --- stderr --- 2024-04-20 00:16:22,382 DEBUG Order has been successfully forwarded to overseer [2024-04-20 00:16:22] - INFO: Check if Gatling simulation started successfully 2024-04-20 00:16:22,383 INFO 2024-04-20 00:16:22,383 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-20 00:16:22,383 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:22,682 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:22,683 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:22,683 DEBUG --- stdout --- 2024-04-20 00:16:22,683 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:22,683 DEBUG --- stderr --- 2024-04-20 00:16:22,683 DEBUG 2024-04-20 00:16:22,683 INFO 2024-04-20 00:16:22,683 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 -- head -n 25 /results/orders/authn-users-7000/stdout.txt | grep "started..." 2024-04-20 00:16:22,683 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-20 00:16:23,444 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-20 00:16:29,289 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-20 00:16:35,057 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-20 00:16:35,058 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:35,058 DEBUG --- stdout --- 2024-04-20 00:16:35,058 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-20 00:16:35,058 DEBUG --- stderr --- 2024-04-20 00:16:35,058 DEBUG ________________________________________________________________________________ [2024-04-20 00:16:35] authn-users-7000 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, 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 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, 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 08s, 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-20 01:18:45] authn-users-7000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-20 01:18:45,691 INFO 2024-04-20 01:18:45,691 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-20 01:18:45,691 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:46,068 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:46,069 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:46,069 DEBUG --- stdout --- 2024-04-20 01:18:46,069 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:46,069 DEBUG --- stderr --- 2024-04-20 01:18:46,069 DEBUG 2024-04-20 01:18:46,069 INFO 2024-04-20 01:18:46,069 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 -- rm -f /results/orders/order.json 2024-04-20 01:18:46,070 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 01:18:46,924 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:46,924 DEBUG --- stdout --- 2024-04-20 01:18:46,926 DEBUG 2024-04-20 01:18:46,926 DEBUG --- stderr --- 2024-04-20 01:18:46,926 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-20 01:18:46] authn-users-7000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-20 01:18:46] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/order.json 2024-04-20 01:18:46,927 INFO 2024-04-20 01:18:46,927 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-20 01:18:46,927 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:47,240 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:47,241 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:47,241 DEBUG --- stdout --- 2024-04-20 01:18:47,241 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:47,241 DEBUG --- stderr --- 2024-04-20 01:18:47,241 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 01:18:47,241 INFO 2024-04-20 01:18:47,241 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-20 01:18:47,241 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:47,538 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:47,538 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:47,538 DEBUG --- stdout --- 2024-04-20 01:18:47,538 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:47,538 DEBUG --- stderr --- 2024-04-20 01:18:47,538 DEBUG 2024-04-20 01:18:47,539 INFO 2024-04-20 01:18:47,539 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-7000/rc.txt" ]; then echo "/results/orders/authn-users-7000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-7000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7000/rc.txt file found" 2024-04-20 01:18:47,539 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 01:18:48,350 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 01:18:48,351 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:48,351 DEBUG --- stdout --- 2024-04-20 01:18:48,351 DEBUG /results/orders/authn-users-7000/rc.txt file found 2024-04-20 01:18:48,351 DEBUG --- stderr --- 2024-04-20 01:18:48,351 DEBUG 2024-04-20 01:18:48,351 INFO 2024-04-20 01:18:48,351 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-20 01:18:48,351 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:48,647 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:48,647 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:48,647 DEBUG --- stdout --- 2024-04-20 01:18:48,647 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:48,647 DEBUG --- stderr --- 2024-04-20 01:18:48,647 DEBUG 2024-04-20 01:18:48,647 INFO 2024-04-20 01:18:48,648 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-7000/rc.txt 2024-04-20 01:18:49,458 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 01:18:49,458 DEBUG --- stdout --- 2024-04-20 01:18:49,458 DEBUG 4 /results/orders/authn-users-7000/rc.txt 2024-04-20 01:18:49,458 DEBUG --- stderr --- 2024-04-20 01:18:49,458 DEBUG 2024-04-20 01:18:49,458 INFO - Download file (size 4k) 2024-04-20 01:18:49,459 INFO 2024-04-20 01:18:49,459 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-20 01:18:49,459 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:49,774 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:49,774 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:49,774 DEBUG --- stdout --- 2024-04-20 01:18:49,775 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:49,775 DEBUG --- stderr --- 2024-04-20 01:18:49,775 DEBUG source : /results/orders/authn-users-7000/rc.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/rc.txt 2024-04-20 01:18:49,775 INFO 2024-04-20 01:18:49,775 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-20 01:18:49,775 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:50,100 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:50,101 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:50,101 DEBUG --- stdout --- 2024-04-20 01:18:50,101 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:50,101 DEBUG --- stderr --- 2024-04-20 01:18:50,101 DEBUG 2024-04-20 01:18:50,101 INFO 2024-04-20 01:18:50,101 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-7000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/rc.txt 2024-04-20 01:18:50,101 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 01:18:50,902 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:50,902 DEBUG --- stdout --- 2024-04-20 01:18:50,902 DEBUG tar: Removing leading `/' from member names 2024-04-20 01:18:50,902 DEBUG --- stderr --- 2024-04-20 01:18:50,902 DEBUG 2024-04-20 01:18:50,903 INFO 2024-04-20 01:18:50,903 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-20 01:18:50,903 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:51,222 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:51,223 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:51,223 DEBUG --- stdout --- 2024-04-20 01:18:51,223 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:51,223 DEBUG --- stderr --- 2024-04-20 01:18:51,223 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 01:18:51,224 INFO 2024-04-20 01:18:51,224 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-20 01:18:51,224 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:51,532 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:51,532 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:51,532 DEBUG --- stdout --- 2024-04-20 01:18:51,532 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:51,532 DEBUG --- stderr --- 2024-04-20 01:18:51,533 DEBUG 2024-04-20 01:18:51,533 INFO 2024-04-20 01:18:51,534 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-7000/stderr.txt" ]; then echo "/results/orders/authn-users-7000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-7000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7000/stderr.txt file found" 2024-04-20 01:18:51,534 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 01:18:52,391 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 01:18:52,393 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:52,393 DEBUG --- stdout --- 2024-04-20 01:18:52,393 DEBUG /results/orders/authn-users-7000/stderr.txt file found 2024-04-20 01:18:52,393 DEBUG --- stderr --- 2024-04-20 01:18:52,393 DEBUG 2024-04-20 01:18:52,394 INFO 2024-04-20 01:18:52,394 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-20 01:18:52,394 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:52,719 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:52,719 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:52,719 DEBUG --- stdout --- 2024-04-20 01:18:52,719 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:52,719 DEBUG --- stderr --- 2024-04-20 01:18:52,719 DEBUG 2024-04-20 01:18:52,720 INFO 2024-04-20 01:18:52,720 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-7000/stderr.txt 2024-04-20 01:18:53,555 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 01:18:53,555 DEBUG --- stdout --- 2024-04-20 01:18:53,555 DEBUG 0 /results/orders/authn-users-7000/stderr.txt 2024-04-20 01:18:53,555 DEBUG --- stderr --- 2024-04-20 01:18:53,555 DEBUG 2024-04-20 01:18:53,555 INFO - Download file (size 0k) 2024-04-20 01:18:53,556 INFO 2024-04-20 01:18:53,556 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-20 01:18:53,556 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:53,865 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:53,865 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:53,865 DEBUG --- stdout --- 2024-04-20 01:18:53,865 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:53,865 DEBUG --- stderr --- 2024-04-20 01:18:53,865 DEBUG source : /results/orders/authn-users-7000/stderr.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/stderr.txt 2024-04-20 01:18:53,866 INFO 2024-04-20 01:18:53,866 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-20 01:18:53,866 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:54,169 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:54,169 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:54,169 DEBUG --- stdout --- 2024-04-20 01:18:54,169 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:54,169 DEBUG --- stderr --- 2024-04-20 01:18:54,169 DEBUG 2024-04-20 01:18:54,170 INFO 2024-04-20 01:18:54,170 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-7000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/stderr.txt 2024-04-20 01:18:54,170 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 01:18:54,918 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:54,919 DEBUG --- stdout --- 2024-04-20 01:18:54,919 DEBUG tar: Removing leading `/' from member names 2024-04-20 01:18:54,919 DEBUG --- stderr --- 2024-04-20 01:18:54,919 DEBUG 2024-04-20 01:18:54,919 INFO 2024-04-20 01:18:54,919 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-20 01:18:54,919 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:55,238 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:55,238 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:55,238 DEBUG --- stdout --- 2024-04-20 01:18:55,238 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:55,238 DEBUG --- stderr --- 2024-04-20 01:18:55,238 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 01:18:55,239 INFO 2024-04-20 01:18:55,239 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-20 01:18:55,239 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:55,553 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:55,553 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:55,553 DEBUG --- stdout --- 2024-04-20 01:18:55,553 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:55,553 DEBUG --- stderr --- 2024-04-20 01:18:55,553 DEBUG 2024-04-20 01:18:55,554 INFO 2024-04-20 01:18:55,554 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-7000/stdout.txt" ]; then echo "/results/orders/authn-users-7000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-7000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7000/stdout.txt file found" 2024-04-20 01:18:55,554 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 01:18:56,376 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 01:18:56,376 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:56,376 DEBUG --- stdout --- 2024-04-20 01:18:56,376 DEBUG /results/orders/authn-users-7000/stdout.txt file found 2024-04-20 01:18:56,376 DEBUG --- stderr --- 2024-04-20 01:18:56,376 DEBUG 2024-04-20 01:18:56,376 INFO 2024-04-20 01:18:56,377 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-20 01:18:56,377 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:56,677 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:56,677 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:56,677 DEBUG --- stdout --- 2024-04-20 01:18:56,677 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:56,677 DEBUG --- stderr --- 2024-04-20 01:18:56,677 DEBUG 2024-04-20 01:18:56,677 INFO 2024-04-20 01:18:56,678 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-7000/stdout.txt 2024-04-20 01:18:57,492 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 01:18:57,492 DEBUG --- stdout --- 2024-04-20 01:18:57,494 DEBUG 508 /results/orders/authn-users-7000/stdout.txt 2024-04-20 01:18:57,494 DEBUG --- stderr --- 2024-04-20 01:18:57,494 DEBUG 2024-04-20 01:18:57,494 INFO - Download file (size 508k) 2024-04-20 01:18:57,494 INFO 2024-04-20 01:18:57,494 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-20 01:18:57,495 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:57,798 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:57,798 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:57,798 DEBUG --- stdout --- 2024-04-20 01:18:57,799 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:57,799 DEBUG --- stderr --- 2024-04-20 01:18:57,799 DEBUG source : /results/orders/authn-users-7000/stdout.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/stdout.txt 2024-04-20 01:18:57,799 INFO 2024-04-20 01:18:57,799 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-20 01:18:57,799 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:58,106 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:58,107 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:58,107 DEBUG --- stdout --- 2024-04-20 01:18:58,107 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:58,107 DEBUG --- stderr --- 2024-04-20 01:18:58,107 DEBUG 2024-04-20 01:18:58,108 INFO 2024-04-20 01:18:58,108 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-7000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/stdout.txt 2024-04-20 01:18:58,108 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 01:18:59,251 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:59,252 DEBUG --- stdout --- 2024-04-20 01:18:59,252 DEBUG tar: Removing leading `/' from member names 2024-04-20 01:18:59,252 DEBUG --- stderr --- 2024-04-20 01:18:59,252 DEBUG ________________________________________________________________________________ [2024-04-20 01:18:59] authn-users-7000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-20 01:18:59,253 INFO 2024-04-20 01:18:59,253 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-20 01:18:59,253 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:59,577 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:59,577 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:59,577 DEBUG --- stdout --- 2024-04-20 01:18:59,578 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:59,578 DEBUG --- stderr --- 2024-04-20 01:18:59,578 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 01:18:59,578 INFO 2024-04-20 01:18:59,578 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-20 01:18:59,578 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:18:59,877 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:18:59,877 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:18:59,877 DEBUG --- stdout --- 2024-04-20 01:18:59,877 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:18:59,877 DEBUG --- stderr --- 2024-04-20 01:18:59,877 DEBUG 2024-04-20 01:18:59,878 INFO 2024-04-20 01:18:59,878 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-7000/cmd.txt" ]; then echo "/results/orders/authn-users-7000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-7000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7000/cmd.txt file found" 2024-04-20 01:18:59,878 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 01:19:00,698 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 01:19:00,698 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:00,698 DEBUG --- stdout --- 2024-04-20 01:19:00,698 DEBUG /results/orders/authn-users-7000/cmd.txt file found 2024-04-20 01:19:00,698 DEBUG --- stderr --- 2024-04-20 01:19:00,698 DEBUG 2024-04-20 01:19:00,699 INFO 2024-04-20 01:19:00,699 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-20 01:19:00,699 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:19:01,020 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:19:01,020 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:01,020 DEBUG --- stdout --- 2024-04-20 01:19:01,021 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:19:01,021 DEBUG --- stderr --- 2024-04-20 01:19:01,021 DEBUG 2024-04-20 01:19:01,021 INFO 2024-04-20 01:19:01,021 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-7000/cmd.txt 2024-04-20 01:19:01,852 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 01:19:01,852 DEBUG --- stdout --- 2024-04-20 01:19:01,853 DEBUG 4 /results/orders/authn-users-7000/cmd.txt 2024-04-20 01:19:01,853 DEBUG --- stderr --- 2024-04-20 01:19:01,853 DEBUG 2024-04-20 01:19:01,853 INFO - Download file (size 4k) 2024-04-20 01:19:01,853 INFO 2024-04-20 01:19:01,853 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-20 01:19:01,853 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:19:02,154 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:19:02,154 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:02,154 DEBUG --- stdout --- 2024-04-20 01:19:02,154 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:19:02,155 DEBUG --- stderr --- 2024-04-20 01:19:02,155 DEBUG source : /results/orders/authn-users-7000/cmd.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/cmd.txt 2024-04-20 01:19:02,155 INFO 2024-04-20 01:19:02,155 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-20 01:19:02,155 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:19:02,459 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:19:02,460 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:02,460 DEBUG --- stdout --- 2024-04-20 01:19:02,460 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:19:02,460 DEBUG --- stderr --- 2024-04-20 01:19:02,460 DEBUG 2024-04-20 01:19:02,460 INFO 2024-04-20 01:19:02,460 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-7000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/cmd.txt 2024-04-20 01:19:02,460 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 01:19:03,213 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:03,213 DEBUG --- stdout --- 2024-04-20 01:19:03,213 DEBUG tar: Removing leading `/' from member names 2024-04-20 01:19:03,213 DEBUG --- stderr --- 2024-04-20 01:19:03,213 DEBUG 2024-04-20 01:19:03,214 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/cmd.txt is small (1 lines), printing all lines: 2024-04-20 01:19:03,214 DEBUG ----- output ----- 2024-04-20 01:19:03,214 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-7000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-20 01:19:03,214 INFO ________________________________________________________________________________ [2024-04-20 01:19:03] authn-users-7000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-20 01:19:03,215 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/rc.txt is small (1 lines), printing all lines: 2024-04-20 01:19:03,215 DEBUG ----- output ----- 2024-04-20 01:19:03,215 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-20 01:19:03,215 INFO ________________________________________________________________________________ [2024-04-20 01:19:03] authn-users-7000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-20 01:19:03,217 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/04_authn-users-7000/stdout.txt is 8746 lines): 2024-04-20 01:19:03,217 DEBUG ----- output ----- 2024-04-20 01:19:03,217 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-20 01:19:03,217 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-20 01:19:03,217 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-20 01:19:03,217 INFO 2024-04-20 01:19:03,217 INFO ================================================================================ 2024-04-20 01:19:03,217 INFO 2024-04-20 01:16:35 5s elapsed 2024-04-20 01:19:03,218 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,218 INFO > Global (OK=2912 KO=0 ) 2024-04-20 01:19:03,218 INFO > userRestLogin (OK=2912 KO=0 ) 2024-04-20 01:19:03,218 INFO 2024-04-20 01:19:03,218 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,218 INFO [- ] 0% 2024-04-20 01:19:03,218 INFO waiting: 25301862 / active: 10 / done: 2912 2024-04-20 01:19:03,218 INFO ================================================================================ 2024-04-20 01:19:03,218 INFO 2024-04-20 01:19:03,218 INFO 2024-04-20 01:19:03,218 INFO ================================================================================ 2024-04-20 01:19:03,218 INFO 2024-04-20 01:16:40 10s elapsed 2024-04-20 01:19:03,218 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,218 INFO > Global (OK=11660 KO=0 ) 2024-04-20 01:19:03,218 INFO > userRestLogin (OK=11660 KO=0 ) 2024-04-20 01:19:03,218 INFO 2024-04-20 01:19:03,218 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,218 INFO [- ] 0% 2024-04-20 01:19:03,218 INFO waiting: 25293105 / active: 20 / done: 11659 2024-04-20 01:19:03,218 INFO ================================================================================ 2024-04-20 01:19:03,219 INFO 2024-04-20 01:19:03,219 INFO 2024-04-20 01:19:03,219 INFO ================================================================================ 2024-04-20 01:19:03,219 INFO 2024-04-20 01:16:45 15s elapsed 2024-04-20 01:19:03,219 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,219 INFO > Global (OK=26225 KO=0 ) 2024-04-20 01:19:03,219 INFO > userRestLogin (OK=26225 KO=0 ) 2024-04-20 01:19:03,219 INFO 2024-04-20 01:19:03,219 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,219 INFO [- ] 0% 2024-04-20 01:19:03,219 INFO waiting: 25278519 / active: 40 / done: 26225 2024-04-20 01:19:03,219 INFO ================================================================================ 2024-04-20 01:19:03,219 INFO 2024-04-20 01:19:03,219 INFO 2024-04-20 01:19:03,219 INFO ================================================================================ 2024-04-20 01:19:03,219 INFO 2024-04-20 01:16:50 20s elapsed 2024-04-20 01:19:03,219 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,219 INFO > Global (OK=46637 KO=0 ) 2024-04-20 01:19:03,219 INFO > userRestLogin (OK=46637 KO=0 ) 2024-04-20 01:19:03,219 INFO 2024-04-20 01:19:03,219 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,219 INFO [- ] 0% 2024-04-20 01:19:03,219 INFO waiting: 25258098 / active: 50 / done: 46636 2024-04-20 01:19:03,219 INFO ================================================================================ 2024-04-20 01:19:03,220 INFO 2024-04-20 01:19:03,220 INFO 2024-04-20 01:19:03,220 INFO ================================================================================ 2024-04-20 01:19:03,220 INFO 2024-04-20 01:16:55 25s elapsed 2024-04-20 01:19:03,220 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,220 INFO > Global (OK=72877 KO=0 ) 2024-04-20 01:19:03,220 INFO > userRestLogin (OK=72877 KO=0 ) 2024-04-20 01:19:03,220 INFO 2024-04-20 01:19:03,220 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,220 INFO [- ] 0% 2024-04-20 01:19:03,220 INFO waiting: 25231844 / active: 63 / done: 72877 2024-04-20 01:19:03,220 INFO ================================================================================ 2024-04-20 01:19:03,220 INFO 2024-04-20 01:19:03,220 INFO 2024-04-20 01:19:03,220 INFO ================================================================================ 2024-04-20 01:19:03,220 INFO 2024-04-20 01:17:00 30s elapsed 2024-04-20 01:19:03,220 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,220 INFO > Global (OK=104745 KO=0 ) 2024-04-20 01:19:03,220 INFO > userRestLogin (OK=104745 KO=0 ) 2024-04-20 01:19:03,220 INFO 2024-04-20 01:19:03,220 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,220 INFO [- ] 0% 2024-04-20 01:19:03,221 INFO waiting: 25199927 / active: 112 / done: 104745 2024-04-20 01:19:03,221 INFO ================================================================================ 2024-04-20 01:19:03,221 INFO 2024-04-20 01:19:03,221 INFO 2024-04-20 01:19:03,221 INFO ================================================================================ 2024-04-20 01:19:03,221 INFO 2024-04-20 01:17:05 35s elapsed 2024-04-20 01:19:03,221 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,221 INFO > Global (OK=139665 KO=0 ) 2024-04-20 01:19:03,221 INFO > userRestLogin (OK=139665 KO=0 ) 2024-04-20 01:19:03,221 INFO 2024-04-20 01:19:03,221 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,221 INFO [- ] 0% 2024-04-20 01:19:03,221 INFO waiting: 25164958 / active: 163 / done: 139663 2024-04-20 01:19:03,221 INFO ================================================================================ 2024-04-20 01:19:03,221 INFO 2024-04-20 01:19:03,221 INFO 2024-04-20 01:19:03,221 INFO ================================================================================ 2024-04-20 01:19:03,221 INFO 2024-04-20 01:17:10 40s elapsed 2024-04-20 01:19:03,221 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,221 INFO > Global (OK=174647 KO=0 ) 2024-04-20 01:19:03,221 INFO > userRestLogin (OK=174647 KO=0 ) 2024-04-20 01:19:03,221 INFO 2024-04-20 01:19:03,221 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,221 INFO [- ] 0% 2024-04-20 01:19:03,221 INFO waiting: 25130039 / active: 99 / done: 174646 2024-04-20 01:19:03,221 INFO ================================================================================ 2024-04-20 01:19:03,222 INFO 2024-04-20 01:19:03,222 INFO 2024-04-20 01:19:03,222 INFO [...] 2024-04-20 01:19:03,225 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,225 INFO > Global (OK=25129746 KO=0 ) 2024-04-20 01:19:03,225 INFO > userRestLogin (OK=25129746 KO=0 ) 2024-04-20 01:19:03,226 INFO 2024-04-20 01:19:03,226 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,226 INFO [#########################################################################-] 99% 2024-04-20 01:19:03,226 INFO waiting: 174812 / active: 226 / done: 25129746 2024-04-20 01:19:03,226 INFO ================================================================================ 2024-04-20 01:19:03,226 INFO 2024-04-20 01:19:03,226 INFO 2024-04-20 01:19:03,226 INFO ================================================================================ 2024-04-20 01:19:03,226 INFO 2024-04-20 02:16:40 3610s elapsed 2024-04-20 01:19:03,226 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,226 INFO > Global (OK=25164577 KO=0 ) 2024-04-20 01:19:03,226 INFO > userRestLogin (OK=25164577 KO=0 ) 2024-04-20 01:19:03,226 INFO 2024-04-20 01:19:03,226 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,226 INFO [#########################################################################-] 99% 2024-04-20 01:19:03,226 INFO waiting: 139991 / active: 217 / done: 25164576 2024-04-20 01:19:03,226 INFO ================================================================================ 2024-04-20 01:19:03,226 INFO 2024-04-20 01:19:03,226 INFO 2024-04-20 01:19:03,226 INFO ================================================================================ 2024-04-20 01:19:03,226 INFO 2024-04-20 02:16:45 3615s elapsed 2024-04-20 01:19:03,226 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,226 INFO > Global (OK=25199724 KO=0 ) 2024-04-20 01:19:03,226 INFO > userRestLogin (OK=25199724 KO=0 ) 2024-04-20 01:19:03,227 INFO 2024-04-20 01:19:03,227 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,227 INFO [#########################################################################-] 99% 2024-04-20 01:19:03,227 INFO waiting: 104826 / active: 236 / done: 25199722 2024-04-20 01:19:03,227 INFO ================================================================================ 2024-04-20 01:19:03,227 INFO 2024-04-20 01:19:03,227 INFO 2024-04-20 01:19:03,227 INFO ================================================================================ 2024-04-20 01:19:03,227 INFO 2024-04-20 02:16:50 3620s elapsed 2024-04-20 01:19:03,227 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,227 INFO > Global (OK=25234920 KO=0 ) 2024-04-20 01:19:03,227 INFO > userRestLogin (OK=25234920 KO=0 ) 2024-04-20 01:19:03,227 INFO 2024-04-20 01:19:03,227 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,227 INFO [#########################################################################-] 99% 2024-04-20 01:19:03,227 INFO waiting: 69706 / active: 159 / done: 25234919 2024-04-20 01:19:03,227 INFO ================================================================================ 2024-04-20 01:19:03,227 INFO 2024-04-20 01:19:03,227 INFO 2024-04-20 01:19:03,227 INFO ================================================================================ 2024-04-20 01:19:03,227 INFO 2024-04-20 02:16:55 3625s elapsed 2024-04-20 01:19:03,227 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,227 INFO > Global (OK=25269845 KO=0 ) 2024-04-20 01:19:03,227 INFO > userRestLogin (OK=25269845 KO=0 ) 2024-04-20 01:19:03,227 INFO 2024-04-20 01:19:03,228 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,228 INFO [#########################################################################-] 99% 2024-04-20 01:19:03,228 INFO waiting: 34785 / active: 155 / done: 25269844 2024-04-20 01:19:03,228 INFO ================================================================================ 2024-04-20 01:19:03,228 INFO 2024-04-20 01:19:03,228 INFO 2024-04-20 01:19:03,228 INFO ================================================================================ 2024-04-20 01:19:03,228 INFO 2024-04-20 02:17:00 3630s elapsed 2024-04-20 01:19:03,228 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,228 INFO > Global (OK=25304648 KO=0 ) 2024-04-20 01:19:03,228 INFO > userRestLogin (OK=25304648 KO=0 ) 2024-04-20 01:19:03,228 INFO 2024-04-20 01:19:03,228 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,228 INFO [#########################################################################-] 99% 2024-04-20 01:19:03,228 INFO waiting: 0 / active: 136 / done: 25304648 2024-04-20 01:19:03,228 INFO ================================================================================ 2024-04-20 01:19:03,228 INFO 2024-04-20 01:19:03,228 INFO 2024-04-20 01:19:03,228 INFO ================================================================================ 2024-04-20 01:19:03,228 INFO 2024-04-20 02:17:00 3630s elapsed 2024-04-20 01:19:03,228 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 01:19:03,228 INFO > Global (OK=25304784 KO=0 ) 2024-04-20 01:19:03,229 INFO > userRestLogin (OK=25304784 KO=0 ) 2024-04-20 01:19:03,229 INFO 2024-04-20 01:19:03,229 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 01:19:03,229 INFO [##########################################################################]100% 2024-04-20 01:19:03,229 INFO waiting: 0 / active: 0 / done: 25304784 2024-04-20 01:19:03,229 INFO ================================================================================ 2024-04-20 01:19:03,229 INFO 2024-04-20 01:19:03,229 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-20 01:19:03,229 INFO 2024-04-20 01:19:03,229 INFO ---------------------- 2024-04-20 01:19:03,229 INFO [20/Apr/2024 02:17:00] Create tar file authn-users-7000.tar.gz 2024-04-20 01:19:03,229 INFO ---------------------- 2024-04-20 01:19:03,229 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-20 01:19:03,229 INFO RESULT_FOLDER=authn-users-7000 2024-04-20 01:19:03,229 INFO SIMULATION_PATH=authn-users-7000/loadtest-20240420001629836 2024-04-20 01:19:03,229 INFO PWD=/results/gatling/authn-users-7000/loadtest-20240420001629836 2024-04-20 01:19:03,229 INFO [cmd] tar -czvf /results/gatling//authn-users-7000.tar.gz --exclude=simulation.log * 2024-04-20 01:19:03,229 INFO /results/gatling 2024-04-20 01:19:03,229 INFO 2024-04-20 01:19:03,229 INFO ---------------------- 2024-04-20 01:19:03,229 INFO [20/Apr/2024 02:17:00] Update /results/gatling//latest 2024-04-20 01:19:03,229 INFO ---------------------- 2024-04-20 01:19:03,229 INFO 2024-04-20 01:19:03,229 INFO ---------------------- 2024-04-20 01:19:03,230 INFO [20/Apr/2024 02:17:00] Update /results/gatling//latest.tar.gz 2024-04-20 01:19:03,230 INFO ---------------------- 2024-04-20 01:19:03,230 INFO ________________________________________________________________________________ [2024-04-20 01:19:03] authn-users-7000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-20 01:19:03,230 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-7000/stderr.txt is small (0 lines), printing all lines: 2024-04-20 01:19:03,230 DEBUG ----- output ----- 2024-04-20 01:19:03,230 DEBUG 2024-04-20 01:19:03,230 INFO ________________________________________________________________________________ [2024-04-20 01:19:03] authn-users-7000 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-20 01:19:03] authn-users-7000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-20 01:19:03] authn-users-7000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped