--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-6500'] preceding_task: authn-users-6500 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 7000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-19 09:58:22] authn-users-7000 pre : N/A ________________________________________________________________________________ [2024-04-19 09:58:22] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-19 09:58:22] - 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-19 09:58:22,201 INFO 2024-04-19 09:58:22,201 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 09:58:22,201 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:22,518 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:22,518 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:22,518 DEBUG --- stdout --- 2024-04-19 09:58:22,518 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:22,518 DEBUG --- stderr --- 2024-04-19 09:58:22,518 DEBUG ________________________________________________________________________________ [2024-04-19 09:58:22] authn-users-7000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-19 09:58:22,519 INFO 2024-04-19 09:58:22,519 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 09:58:22,519 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:22,834 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:22,835 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:22,835 DEBUG --- stdout --- 2024-04-19 09:58:22,835 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:22,835 DEBUG --- stderr --- 2024-04-19 09:58:22,835 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-19 09:58:22] authn-users-7000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-19 09:58:22,835 INFO 2024-04-19 09:58:22,835 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 09:58:22,836 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:23,136 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:23,136 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:23,136 DEBUG --- stdout --- 2024-04-19 09:58:23,136 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:23,136 DEBUG --- stderr --- 2024-04-19 09:58:23,136 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_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-19 09:58:23] 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-19 09:58:23,138 INFO 2024-04-19 09:58:23,138 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-19 09:58:23,138 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 09:58:23,369 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 09:58:23,369 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:23,369 DEBUG --- stdout --- 2024-04-19 09:58:23,369 DEBUG WAITING 2024-04-19 09:58:23,369 DEBUG --- stderr --- 2024-04-19 09:58:23,369 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-19 09:58:23] authn-users-7000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-19 09:58:23,370 INFO 2024-04-19 09:58:23,370 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 09:58:23,370 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:23,673 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:23,673 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:23,674 DEBUG --- stdout --- 2024-04-19 09:58:23,674 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:23,674 DEBUG --- stderr --- 2024-04-19 09:58:23,674 DEBUG 2024-04-19 09:58:23,674 INFO 2024-04-19 09:58:23,674 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7000/order.json overseer-0-5c67bf94db-77mh9:/results/orders/order.json 2024-04-19 09:58:23,674 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 09:58:25,053 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:25,053 DEBUG --- stdout --- 2024-04-19 09:58:25,054 DEBUG 2024-04-19 09:58:25,054 DEBUG --- stderr --- 2024-04-19 09:58:25,054 DEBUG Order has been successfully forwarded to overseer [2024-04-19 09:58:25] - INFO: Check if Gatling simulation started successfully 2024-04-19 09:58:25,054 INFO 2024-04-19 09:58:25,054 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 09:58:25,054 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 09:58:25,355 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 09:58:25,355 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:25,355 DEBUG --- stdout --- 2024-04-19 09:58:25,355 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 09:58:25,355 DEBUG --- stderr --- 2024-04-19 09:58:25,355 DEBUG 2024-04-19 09:58:25,355 INFO 2024-04-19 09:58:25,355 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-19 09:58:25,356 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 09:58:26,129 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-19 09:58:31,955 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-19 09:58:37,720 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-19 09:58:37,721 INFO [loop_until]: OK (rc = 0) 2024-04-19 09:58:37,721 DEBUG --- stdout --- 2024-04-19 09:58:37,721 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 09:58:37,721 DEBUG --- stderr --- 2024-04-19 09:58:37,721 DEBUG ________________________________________________________________________________ [2024-04-19 09:58:37] 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 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 08s, 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-19 11:00:48] authn-users-7000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-19 11:00:48,605 INFO 2024-04-19 11:00:48,605 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:48,605 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:48,928 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:48,928 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:48,928 DEBUG --- stdout --- 2024-04-19 11:00:48,928 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:48,928 DEBUG --- stderr --- 2024-04-19 11:00:48,928 DEBUG 2024-04-19 11:00:48,929 INFO 2024-04-19 11:00:48,929 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 -- rm -f /results/orders/order.json 2024-04-19 11:00:48,929 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:00:49,721 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:49,721 DEBUG --- stdout --- 2024-04-19 11:00:49,721 DEBUG 2024-04-19 11:00:49,721 DEBUG --- stderr --- 2024-04-19 11:00:49,721 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-19 11:00:49] authn-users-7000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-19 11:00:49] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7000/order.json 2024-04-19 11:00:49,722 INFO 2024-04-19 11:00:49,722 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:49,722 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:50,044 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:50,044 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:50,045 DEBUG --- stdout --- 2024-04-19 11:00:50,045 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:50,045 DEBUG --- stderr --- 2024-04-19 11:00:50,045 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:00:50,045 INFO 2024-04-19 11:00:50,045 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:50,045 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:50,371 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:50,371 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:50,371 DEBUG --- stdout --- 2024-04-19 11:00:50,371 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:50,371 DEBUG --- stderr --- 2024-04-19 11:00:50,371 DEBUG 2024-04-19 11:00:50,372 INFO 2024-04-19 11:00:50,372 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-19 11:00:50,372 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:00:51,199 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:00:51,200 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:51,200 DEBUG --- stdout --- 2024-04-19 11:00:51,200 DEBUG /results/orders/authn-users-7000/rc.txt file found 2024-04-19 11:00:51,200 DEBUG --- stderr --- 2024-04-19 11:00:51,200 DEBUG 2024-04-19 11:00:51,200 INFO 2024-04-19 11:00:51,200 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:51,200 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:51,531 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:51,531 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:51,531 DEBUG --- stdout --- 2024-04-19 11:00:51,531 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:51,531 DEBUG --- stderr --- 2024-04-19 11:00:51,531 DEBUG 2024-04-19 11:00:51,531 INFO 2024-04-19 11:00:51,532 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-19 11:00:52,381 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:00:52,381 DEBUG --- stdout --- 2024-04-19 11:00:52,381 DEBUG 4 /results/orders/authn-users-7000/rc.txt 2024-04-19 11:00:52,381 DEBUG --- stderr --- 2024-04-19 11:00:52,381 DEBUG 2024-04-19 11:00:52,381 INFO - Download file (size 4k) 2024-04-19 11:00:52,382 INFO 2024-04-19 11:00:52,382 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:52,382 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:52,739 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:52,739 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:52,739 DEBUG --- stdout --- 2024-04-19 11:00:52,739 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:52,739 DEBUG --- stderr --- 2024-04-19 11:00:52,739 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/05_authn-users-7000/rc.txt 2024-04-19 11:00:52,739 INFO 2024-04-19 11:00:52,739 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:52,739 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:53,084 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:53,085 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:53,085 DEBUG --- stdout --- 2024-04-19 11:00:53,086 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:53,086 DEBUG --- stderr --- 2024-04-19 11:00:53,086 DEBUG 2024-04-19 11:00:53,086 INFO 2024-04-19 11:00:53,086 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/05_authn-users-7000/rc.txt 2024-04-19 11:00:53,086 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:00:53,853 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:53,853 DEBUG --- stdout --- 2024-04-19 11:00:53,853 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:00:53,853 DEBUG --- stderr --- 2024-04-19 11:00:53,853 DEBUG 2024-04-19 11:00:53,853 INFO 2024-04-19 11:00:53,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-19 11:00:53,853 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:54,165 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:54,166 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:54,166 DEBUG --- stdout --- 2024-04-19 11:00:54,166 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:54,166 DEBUG --- stderr --- 2024-04-19 11:00:54,166 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:00:54,166 INFO 2024-04-19 11:00:54,167 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:54,167 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:54,486 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:54,486 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:54,486 DEBUG --- stdout --- 2024-04-19 11:00:54,487 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:54,487 DEBUG --- stderr --- 2024-04-19 11:00:54,487 DEBUG 2024-04-19 11:00:54,487 INFO 2024-04-19 11:00:54,487 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-19 11:00:54,487 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:00:55,333 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:00:55,334 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:55,334 DEBUG --- stdout --- 2024-04-19 11:00:55,334 DEBUG /results/orders/authn-users-7000/stderr.txt file found 2024-04-19 11:00:55,334 DEBUG --- stderr --- 2024-04-19 11:00:55,334 DEBUG 2024-04-19 11:00:55,334 INFO 2024-04-19 11:00:55,334 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:55,334 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:55,700 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:55,700 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:55,700 DEBUG --- stdout --- 2024-04-19 11:00:55,701 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:55,701 DEBUG --- stderr --- 2024-04-19 11:00:55,701 DEBUG 2024-04-19 11:00:55,701 INFO 2024-04-19 11:00:55,701 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-19 11:00:56,574 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:00:56,574 DEBUG --- stdout --- 2024-04-19 11:00:56,574 DEBUG 0 /results/orders/authn-users-7000/stderr.txt 2024-04-19 11:00:56,574 DEBUG --- stderr --- 2024-04-19 11:00:56,574 DEBUG 2024-04-19 11:00:56,574 INFO - Download file (size 0k) 2024-04-19 11:00:56,575 INFO 2024-04-19 11:00:56,575 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:56,575 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:56,882 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:56,882 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:56,882 DEBUG --- stdout --- 2024-04-19 11:00:56,882 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:56,882 DEBUG --- stderr --- 2024-04-19 11:00:56,882 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/05_authn-users-7000/stderr.txt 2024-04-19 11:00:56,883 INFO 2024-04-19 11:00:56,883 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:56,883 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:57,199 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:57,199 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:57,199 DEBUG --- stdout --- 2024-04-19 11:00:57,199 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:57,199 DEBUG --- stderr --- 2024-04-19 11:00:57,199 DEBUG 2024-04-19 11:00:57,200 INFO 2024-04-19 11:00:57,200 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/05_authn-users-7000/stderr.txt 2024-04-19 11:00:57,200 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:00:57,970 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:57,970 DEBUG --- stdout --- 2024-04-19 11:00:57,971 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:00:57,971 DEBUG --- stderr --- 2024-04-19 11:00:57,971 DEBUG 2024-04-19 11:00:57,971 INFO 2024-04-19 11:00:57,971 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:57,971 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:58,322 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:58,322 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:58,322 DEBUG --- stdout --- 2024-04-19 11:00:58,322 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:58,322 DEBUG --- stderr --- 2024-04-19 11:00:58,322 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:00:58,322 INFO 2024-04-19 11:00:58,323 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:58,323 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:58,655 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:58,655 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:58,655 DEBUG --- stdout --- 2024-04-19 11:00:58,655 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:58,655 DEBUG --- stderr --- 2024-04-19 11:00:58,655 DEBUG 2024-04-19 11:00:58,656 INFO 2024-04-19 11:00:58,656 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-19 11:00:58,656 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:00:59,545 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:00:59,545 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:59,545 DEBUG --- stdout --- 2024-04-19 11:00:59,545 DEBUG /results/orders/authn-users-7000/stdout.txt file found 2024-04-19 11:00:59,545 DEBUG --- stderr --- 2024-04-19 11:00:59,545 DEBUG 2024-04-19 11:00:59,545 INFO 2024-04-19 11:00:59,545 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:00:59,546 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:00:59,868 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:00:59,869 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:00:59,869 DEBUG --- stdout --- 2024-04-19 11:00:59,869 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:00:59,869 DEBUG --- stderr --- 2024-04-19 11:00:59,869 DEBUG 2024-04-19 11:00:59,869 INFO 2024-04-19 11:00:59,869 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-19 11:01:00,683 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:01:00,683 DEBUG --- stdout --- 2024-04-19 11:01:00,683 DEBUG 508 /results/orders/authn-users-7000/stdout.txt 2024-04-19 11:01:00,683 DEBUG --- stderr --- 2024-04-19 11:01:00,683 DEBUG 2024-04-19 11:01:00,683 INFO - Download file (size 508k) 2024-04-19 11:01:00,683 INFO 2024-04-19 11:01:00,684 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:01:00,684 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:00,983 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:00,983 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:00,983 DEBUG --- stdout --- 2024-04-19 11:01:00,983 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:00,983 DEBUG --- stderr --- 2024-04-19 11:01:00,983 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/05_authn-users-7000/stdout.txt 2024-04-19 11:01:00,983 INFO 2024-04-19 11:01:00,983 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:01:00,983 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:01,285 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:01,286 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:01,286 DEBUG --- stdout --- 2024-04-19 11:01:01,286 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:01,286 DEBUG --- stderr --- 2024-04-19 11:01:01,286 DEBUG 2024-04-19 11:01:01,286 INFO 2024-04-19 11:01:01,286 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/05_authn-users-7000/stdout.txt 2024-04-19 11:01:01,286 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:01:02,348 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:02,348 DEBUG --- stdout --- 2024-04-19 11:01:02,348 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:01:02,348 DEBUG --- stderr --- 2024-04-19 11:01:02,348 DEBUG ________________________________________________________________________________ [2024-04-19 11:01:02] authn-users-7000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-19 11:01:02,349 INFO 2024-04-19 11:01:02,349 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:01:02,349 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:02,678 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:02,678 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:02,678 DEBUG --- stdout --- 2024-04-19 11:01:02,678 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:02,678 DEBUG --- stderr --- 2024-04-19 11:01:02,678 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 11:01:02,679 INFO 2024-04-19 11:01:02,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-19 11:01:02,679 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:03,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:03,029 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:03,029 DEBUG --- stdout --- 2024-04-19 11:01:03,029 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:03,029 DEBUG --- stderr --- 2024-04-19 11:01:03,029 DEBUG 2024-04-19 11:01:03,030 INFO 2024-04-19 11:01:03,030 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-19 11:01:03,030 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 11:01:03,864 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 11:01:03,865 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:03,865 DEBUG --- stdout --- 2024-04-19 11:01:03,865 DEBUG /results/orders/authn-users-7000/cmd.txt file found 2024-04-19 11:01:03,865 DEBUG --- stderr --- 2024-04-19 11:01:03,865 DEBUG 2024-04-19 11:01:03,866 INFO 2024-04-19 11:01:03,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-19 11:01:03,866 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:04,221 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:04,221 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:04,221 DEBUG --- stdout --- 2024-04-19 11:01:04,221 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:04,221 DEBUG --- stderr --- 2024-04-19 11:01:04,221 DEBUG 2024-04-19 11:01:04,221 INFO 2024-04-19 11:01:04,222 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-7000/cmd.txt 2024-04-19 11:01:05,089 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 11:01:05,089 DEBUG --- stdout --- 2024-04-19 11:01:05,089 DEBUG 4 /results/orders/authn-users-7000/cmd.txt 2024-04-19 11:01:05,090 DEBUG --- stderr --- 2024-04-19 11:01:05,090 DEBUG 2024-04-19 11:01:05,090 INFO - Download file (size 4k) 2024-04-19 11:01:05,090 INFO 2024-04-19 11:01:05,090 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:01:05,090 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:05,391 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:05,392 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:05,392 DEBUG --- stdout --- 2024-04-19 11:01:05,392 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:05,392 DEBUG --- stderr --- 2024-04-19 11:01:05,392 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/05_authn-users-7000/cmd.txt 2024-04-19 11:01:05,392 INFO 2024-04-19 11:01:05,392 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 11:01:05,392 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 11:01:05,693 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 11:01:05,694 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:05,694 DEBUG --- stdout --- 2024-04-19 11:01:05,694 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 11:01:05,694 DEBUG --- stderr --- 2024-04-19 11:01:05,694 DEBUG 2024-04-19 11:01:05,694 INFO 2024-04-19 11:01:05,694 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/05_authn-users-7000/cmd.txt 2024-04-19 11:01:05,694 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 11:01:06,440 INFO [loop_until]: OK (rc = 0) 2024-04-19 11:01:06,440 DEBUG --- stdout --- 2024-04-19 11:01:06,440 DEBUG tar: Removing leading `/' from member names 2024-04-19 11:01:06,440 DEBUG --- stderr --- 2024-04-19 11:01:06,440 DEBUG 2024-04-19 11:01:06,440 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7000/cmd.txt is small (1 lines), printing all lines: 2024-04-19 11:01:06,440 DEBUG ----- output ----- 2024-04-19 11:01:06,440 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-19 11:01:06,440 INFO ________________________________________________________________________________ [2024-04-19 11:01:06] authn-users-7000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-19 11:01:06,441 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7000/rc.txt is small (1 lines), printing all lines: 2024-04-19 11:01:06,441 DEBUG ----- output ----- 2024-04-19 11:01:06,441 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-19 11:01:06,441 INFO ________________________________________________________________________________ [2024-04-19 11:01:06] authn-users-7000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-19 11:01:06,442 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7000/stdout.txt is 8746 lines): 2024-04-19 11:01:06,442 DEBUG ----- output ----- 2024-04-19 11:01:06,442 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-19 11:01:06,442 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-19 11:01:06,442 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 11:01:06,442 INFO 2024-04-19 11:01:06,442 INFO ================================================================================ 2024-04-19 11:01:06,443 INFO 2024-04-19 10:58:38 5s elapsed 2024-04-19 11:01:06,443 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,443 INFO > Global (OK=2924 KO=0 ) 2024-04-19 11:01:06,443 INFO > userRestLogin (OK=2924 KO=0 ) 2024-04-19 11:01:06,443 INFO 2024-04-19 11:01:06,443 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,443 INFO [- ] 0% 2024-04-19 11:01:06,443 INFO waiting: 25302745 / active: 12 / done: 2924 2024-04-19 11:01:06,443 INFO ================================================================================ 2024-04-19 11:01:06,443 INFO 2024-04-19 11:01:06,443 INFO 2024-04-19 11:01:06,443 INFO ================================================================================ 2024-04-19 11:01:06,443 INFO 2024-04-19 10:58:43 10s elapsed 2024-04-19 11:01:06,443 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,443 INFO > Global (OK=11654 KO=0 ) 2024-04-19 11:01:06,443 INFO > userRestLogin (OK=11654 KO=0 ) 2024-04-19 11:01:06,443 INFO 2024-04-19 11:01:06,443 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,443 INFO [- ] 0% 2024-04-19 11:01:06,443 INFO waiting: 25294005 / active: 22 / done: 11654 2024-04-19 11:01:06,443 INFO ================================================================================ 2024-04-19 11:01:06,443 INFO 2024-04-19 11:01:06,443 INFO 2024-04-19 11:01:06,443 INFO ================================================================================ 2024-04-19 11:01:06,443 INFO 2024-04-19 10:58:48 15s elapsed 2024-04-19 11:01:06,443 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,443 INFO > Global (OK=26226 KO=0 ) 2024-04-19 11:01:06,443 INFO > userRestLogin (OK=26226 KO=0 ) 2024-04-19 11:01:06,443 INFO 2024-04-19 11:01:06,443 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,443 INFO [- ] 0% 2024-04-19 11:01:06,443 INFO waiting: 25279412 / active: 43 / done: 26226 2024-04-19 11:01:06,443 INFO ================================================================================ 2024-04-19 11:01:06,443 INFO 2024-04-19 11:01:06,443 INFO 2024-04-19 11:01:06,443 INFO ================================================================================ 2024-04-19 11:01:06,443 INFO 2024-04-19 10:58:53 20s elapsed 2024-04-19 11:01:06,443 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,443 INFO > Global (OK=46683 KO=0 ) 2024-04-19 11:01:06,443 INFO > userRestLogin (OK=46683 KO=0 ) 2024-04-19 11:01:06,443 INFO 2024-04-19 11:01:06,444 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,444 INFO [- ] 0% 2024-04-19 11:01:06,444 INFO waiting: 25258948 / active: 51 / done: 46682 2024-04-19 11:01:06,444 INFO ================================================================================ 2024-04-19 11:01:06,444 INFO 2024-04-19 11:01:06,444 INFO 2024-04-19 11:01:06,444 INFO ================================================================================ 2024-04-19 11:01:06,444 INFO 2024-04-19 10:58:58 25s elapsed 2024-04-19 11:01:06,444 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,444 INFO > Global (OK=72928 KO=0 ) 2024-04-19 11:01:06,444 INFO > userRestLogin (OK=72928 KO=0 ) 2024-04-19 11:01:06,444 INFO 2024-04-19 11:01:06,444 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,444 INFO [- ] 0% 2024-04-19 11:01:06,444 INFO waiting: 25232675 / active: 78 / done: 72928 2024-04-19 11:01:06,444 INFO ================================================================================ 2024-04-19 11:01:06,444 INFO 2024-04-19 11:01:06,444 INFO 2024-04-19 11:01:06,444 INFO ================================================================================ 2024-04-19 11:01:06,444 INFO 2024-04-19 10:59:03 30s elapsed 2024-04-19 11:01:06,444 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,444 INFO > Global (OK=104597 KO=0 ) 2024-04-19 11:01:06,444 INFO > userRestLogin (OK=104597 KO=0 ) 2024-04-19 11:01:06,444 INFO 2024-04-19 11:01:06,444 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,444 INFO [- ] 0% 2024-04-19 11:01:06,444 INFO waiting: 25200790 / active: 294 / done: 104597 2024-04-19 11:01:06,444 INFO ================================================================================ 2024-04-19 11:01:06,444 INFO 2024-04-19 11:01:06,444 INFO 2024-04-19 11:01:06,444 INFO ================================================================================ 2024-04-19 11:01:06,444 INFO 2024-04-19 10:59:08 35s elapsed 2024-04-19 11:01:06,444 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,444 INFO > Global (OK=139607 KO=0 ) 2024-04-19 11:01:06,444 INFO > userRestLogin (OK=139607 KO=0 ) 2024-04-19 11:01:06,444 INFO 2024-04-19 11:01:06,444 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,444 INFO [- ] 0% 2024-04-19 11:01:06,444 INFO waiting: 25165731 / active: 343 / done: 139607 2024-04-19 11:01:06,444 INFO ================================================================================ 2024-04-19 11:01:06,444 INFO 2024-04-19 11:01:06,444 INFO 2024-04-19 11:01:06,445 INFO ================================================================================ 2024-04-19 11:01:06,445 INFO 2024-04-19 10:59:13 40s elapsed 2024-04-19 11:01:06,445 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,445 INFO > Global (OK=174999 KO=0 ) 2024-04-19 11:01:06,445 INFO > userRestLogin (OK=174999 KO=0 ) 2024-04-19 11:01:06,445 INFO 2024-04-19 11:01:06,445 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,445 INFO [- ] 0% 2024-04-19 11:01:06,445 INFO waiting: 25130492 / active: 190 / done: 174999 2024-04-19 11:01:06,445 INFO ================================================================================ 2024-04-19 11:01:06,445 INFO 2024-04-19 11:01:06,445 INFO 2024-04-19 11:01:06,445 INFO [...] 2024-04-19 11:01:06,447 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,447 INFO > Global (OK=25130828 KO=0 ) 2024-04-19 11:01:06,447 INFO > userRestLogin (OK=25130828 KO=0 ) 2024-04-19 11:01:06,447 INFO 2024-04-19 11:01:06,448 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,448 INFO [#########################################################################-] 99% 2024-04-19 11:01:06,448 INFO waiting: 174463 / active: 391 / done: 25130827 2024-04-19 11:01:06,448 INFO ================================================================================ 2024-04-19 11:01:06,448 INFO 2024-04-19 11:01:06,448 INFO 2024-04-19 11:01:06,448 INFO ================================================================================ 2024-04-19 11:01:06,448 INFO 2024-04-19 11:58:43 3610s elapsed 2024-04-19 11:01:06,448 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,448 INFO > Global (OK=25165627 KO=0 ) 2024-04-19 11:01:06,448 INFO > userRestLogin (OK=25165627 KO=0 ) 2024-04-19 11:01:06,448 INFO 2024-04-19 11:01:06,448 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,448 INFO [#########################################################################-] 99% 2024-04-19 11:01:06,448 INFO waiting: 139608 / active: 446 / done: 25165627 2024-04-19 11:01:06,448 INFO ================================================================================ 2024-04-19 11:01:06,448 INFO 2024-04-19 11:01:06,448 INFO 2024-04-19 11:01:06,448 INFO ================================================================================ 2024-04-19 11:01:06,448 INFO 2024-04-19 11:58:48 3615s elapsed 2024-04-19 11:01:06,448 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,448 INFO > Global (OK=25200040 KO=0 ) 2024-04-19 11:01:06,448 INFO > userRestLogin (OK=25200040 KO=0 ) 2024-04-19 11:01:06,448 INFO 2024-04-19 11:01:06,448 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,448 INFO [#########################################################################-] 99% 2024-04-19 11:01:06,448 INFO waiting: 104560 / active: 1083 / done: 25200038 2024-04-19 11:01:06,448 INFO ================================================================================ 2024-04-19 11:01:06,448 INFO 2024-04-19 11:01:06,448 INFO 2024-04-19 11:01:06,448 INFO ================================================================================ 2024-04-19 11:01:06,448 INFO 2024-04-19 11:58:53 3620s elapsed 2024-04-19 11:01:06,448 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,448 INFO > Global (OK=25235573 KO=0 ) 2024-04-19 11:01:06,448 INFO > userRestLogin (OK=25235573 KO=0 ) 2024-04-19 11:01:06,448 INFO 2024-04-19 11:01:06,448 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,448 INFO [#########################################################################-] 99% 2024-04-19 11:01:06,448 INFO waiting: 69645 / active: 464 / done: 25235572 2024-04-19 11:01:06,449 INFO ================================================================================ 2024-04-19 11:01:06,449 INFO 2024-04-19 11:01:06,449 INFO 2024-04-19 11:01:06,449 INFO ================================================================================ 2024-04-19 11:01:06,449 INFO 2024-04-19 11:58:58 3625s elapsed 2024-04-19 11:01:06,449 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,449 INFO > Global (OK=25270164 KO=0 ) 2024-04-19 11:01:06,449 INFO > userRestLogin (OK=25270164 KO=0 ) 2024-04-19 11:01:06,449 INFO 2024-04-19 11:01:06,449 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,449 INFO [#########################################################################-] 99% 2024-04-19 11:01:06,449 INFO waiting: 35064 / active: 455 / done: 25270162 2024-04-19 11:01:06,449 INFO ================================================================================ 2024-04-19 11:01:06,449 INFO 2024-04-19 11:01:06,449 INFO 2024-04-19 11:01:06,449 INFO ================================================================================ 2024-04-19 11:01:06,449 INFO 2024-04-19 11:59:03 3630s elapsed 2024-04-19 11:01:06,449 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,449 INFO > Global (OK=25305136 KO=0 ) 2024-04-19 11:01:06,449 INFO > userRestLogin (OK=25305136 KO=0 ) 2024-04-19 11:01:06,449 INFO 2024-04-19 11:01:06,449 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,449 INFO [#########################################################################-] 99% 2024-04-19 11:01:06,449 INFO waiting: 0 / active: 547 / done: 25305134 2024-04-19 11:01:06,449 INFO ================================================================================ 2024-04-19 11:01:06,449 INFO 2024-04-19 11:01:06,449 INFO 2024-04-19 11:01:06,449 INFO ================================================================================ 2024-04-19 11:01:06,449 INFO 2024-04-19 11:59:03 3630s elapsed 2024-04-19 11:01:06,449 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 11:01:06,449 INFO > Global (OK=25305681 KO=0 ) 2024-04-19 11:01:06,449 INFO > userRestLogin (OK=25305681 KO=0 ) 2024-04-19 11:01:06,449 INFO 2024-04-19 11:01:06,449 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 11:01:06,449 INFO [##########################################################################]100% 2024-04-19 11:01:06,449 INFO waiting: 0 / active: 0 / done: 25305681 2024-04-19 11:01:06,449 INFO ================================================================================ 2024-04-19 11:01:06,449 INFO 2024-04-19 11:01:06,449 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-19 11:01:06,449 INFO 2024-04-19 11:01:06,449 INFO ---------------------- 2024-04-19 11:01:06,450 INFO [19/Apr/2024 11:59:03] Create tar file authn-users-7000.tar.gz 2024-04-19 11:01:06,450 INFO ---------------------- 2024-04-19 11:01:06,450 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-19 11:01:06,450 INFO RESULT_FOLDER=authn-users-7000 2024-04-19 11:01:06,450 INFO SIMULATION_PATH=authn-users-7000/loadtest-20240419095832416 2024-04-19 11:01:06,450 INFO PWD=/results/gatling/authn-users-7000/loadtest-20240419095832416 2024-04-19 11:01:06,450 INFO [cmd] tar -czvf /results/gatling//authn-users-7000.tar.gz --exclude=simulation.log * 2024-04-19 11:01:06,450 INFO /results/gatling 2024-04-19 11:01:06,450 INFO 2024-04-19 11:01:06,450 INFO ---------------------- 2024-04-19 11:01:06,450 INFO [19/Apr/2024 11:59:03] Update /results/gatling//latest 2024-04-19 11:01:06,450 INFO ---------------------- 2024-04-19 11:01:06,450 INFO 2024-04-19 11:01:06,450 INFO ---------------------- 2024-04-19 11:01:06,450 INFO [19/Apr/2024 11:59:03] Update /results/gatling//latest.tar.gz 2024-04-19 11:01:06,450 INFO ---------------------- 2024-04-19 11:01:06,450 INFO ________________________________________________________________________________ [2024-04-19 11:01:06] authn-users-7000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-19 11:01:06,450 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7000/stderr.txt is small (0 lines), printing all lines: 2024-04-19 11:01:06,450 DEBUG ----- output ----- 2024-04-19 11:01:06,450 DEBUG 2024-04-19 11:01:06,450 INFO ________________________________________________________________________________ [2024-04-19 11:01:06] 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-19 11:01:06] authn-users-7000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-19 11:01:06] authn-users-7000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped