--Task-- name: authn-users-1000 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-500'] preceding_task: authn-users-500 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 1000, 'rampup-duration': 30, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-10 00:34:38] authn-users-1000 pre : N/A ________________________________________________________________________________ [2024-04-10 00:34:38] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-10 00:34:38] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-1000/stdout.txt Pod log : /results/orders/authn-users-1000 2024-04-10 00:34:38,089 INFO 2024-04-10 00:34:38,089 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-10 00:34:38,089 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:38,396 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:38,396 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:38,396 DEBUG --- stdout --- 2024-04-10 00:34:38,396 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:38,396 DEBUG --- stderr --- 2024-04-10 00:34:38,396 DEBUG ________________________________________________________________________________ [2024-04-10 00:34:38] authn-users-1000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-10 00:34:38,397 INFO 2024-04-10 00:34:38,397 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-10 00:34:38,397 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:38,711 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:38,711 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:38,711 DEBUG --- stdout --- 2024-04-10 00:34:38,711 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:38,711 DEBUG --- stderr --- 2024-04-10 00:34:38,711 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-1000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-10 00:34:38] authn-users-1000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-10 00:34:38,712 INFO 2024-04-10 00:34:38,712 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-10 00:34:38,712 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:39,026 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:39,026 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:39,026 DEBUG --- stdout --- 2024-04-10 00:34:39,026 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:39,027 DEBUG --- stderr --- 2024-04-10 00:34:39,027 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/order.json { "name": "authn-users-1000", "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-1000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-1000", "environment": { "JAVA_OPTS": "-Dam_host=xlou.iam.xlou-cdm.engineeringpit.com -DamadminPassword=**** -DamadminUsername=amadmin -Dduration=3600 -Dnum_users=1000000 -Drampup_duration=30 -DtestCase=am.AMAuthN -DtestMode=open -Dthroughput=1000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-10 00:34:39] authn-users-1000 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-10 00:34:39,028 INFO 2024-04-10 00:34:39,028 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-10 00:34:39,028 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-10 00:34:39,266 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 00:34:39,266 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:39,266 DEBUG --- stdout --- 2024-04-10 00:34:39,266 DEBUG WAITING 2024-04-10 00:34:39,266 DEBUG --- stderr --- 2024-04-10 00:34:39,266 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-10 00:34:39] authn-users-1000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-10 00:34:39,267 INFO 2024-04-10 00:34:39,267 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-10 00:34:39,267 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:39,593 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:39,593 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:39,593 DEBUG --- stdout --- 2024-04-10 00:34:39,593 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:39,593 DEBUG --- stderr --- 2024-04-10 00:34:39,593 DEBUG 2024-04-10 00:34:39,594 INFO 2024-04-10 00:34:39,594 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/order.json overseer-0-6f8784665d-zq5hb:/results/orders/order.json 2024-04-10 00:34:39,594 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 00:34:40,999 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:40,999 DEBUG --- stdout --- 2024-04-10 00:34:40,999 DEBUG 2024-04-10 00:34:41,000 DEBUG --- stderr --- 2024-04-10 00:34:41,000 DEBUG Order has been successfully forwarded to overseer [2024-04-10 00:34:40] - INFO: Check if Gatling simulation started successfully 2024-04-10 00:34:41,000 INFO 2024-04-10 00:34:41,000 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-10 00:34:41,000 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:41,364 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:41,364 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:41,364 DEBUG --- stdout --- 2024-04-10 00:34:41,364 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:41,364 DEBUG --- stderr --- 2024-04-10 00:34:41,364 DEBUG 2024-04-10 00:34:41,365 INFO 2024-04-10 00:34:41,365 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb -- head -n 25 /results/orders/authn-users-1000/stdout.txt | grep "started..." 2024-04-10 00:34:41,365 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-10 00:34:42,181 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-10 00:34:47,959 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-04-10 00:34:47,959 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:47,959 DEBUG --- stdout --- 2024-04-10 00:34:47,959 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-10 00:34:47,959 DEBUG --- stderr --- 2024-04-10 00:34:47,959 DEBUG ________________________________________________________________________________ [2024-04-10 00:34:47] authn-users-1000 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-10 01:36:58] authn-users-1000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-10 01:36:58,437 INFO 2024-04-10 01:36:58,438 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-10 01:36:58,438 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:36:58,825 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:36:58,825 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:36:58,825 DEBUG --- stdout --- 2024-04-10 01:36:58,826 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:36:58,826 DEBUG --- stderr --- 2024-04-10 01:36:58,826 DEBUG 2024-04-10 01:36:58,826 INFO 2024-04-10 01:36:58,826 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb -- rm -f /results/orders/order.json 2024-04-10 01:36:58,826 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 01:36:59,641 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:36:59,641 DEBUG --- stdout --- 2024-04-10 01:36:59,642 DEBUG 2024-04-10 01:36:59,642 DEBUG --- stderr --- 2024-04-10 01:36:59,642 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-10 01:36:59] authn-users-1000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-10 01:36:59] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/order.json 2024-04-10 01:36:59,643 INFO 2024-04-10 01:36:59,643 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-10 01:36:59,643 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:36:59,956 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:36:59,956 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:36:59,956 DEBUG --- stdout --- 2024-04-10 01:36:59,956 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:36:59,956 DEBUG --- stderr --- 2024-04-10 01:36:59,956 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 01:36:59,957 INFO 2024-04-10 01:36:59,957 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-10 01:36:59,957 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:00,280 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:00,280 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:00,280 DEBUG --- stdout --- 2024-04-10 01:37:00,280 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:00,280 DEBUG --- stderr --- 2024-04-10 01:37:00,280 DEBUG 2024-04-10 01:37:00,281 INFO 2024-04-10 01:37:00,281 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-1000/rc.txt" ]; then echo "/results/orders/authn-users-1000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-1000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-1000/rc.txt file found" 2024-04-10 01:37:00,281 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 01:37:01,110 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 01:37:01,110 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:01,111 DEBUG --- stdout --- 2024-04-10 01:37:01,111 DEBUG /results/orders/authn-users-1000/rc.txt file found 2024-04-10 01:37:01,111 DEBUG --- stderr --- 2024-04-10 01:37:01,111 DEBUG 2024-04-10 01:37:01,111 INFO 2024-04-10 01:37:01,111 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-10 01:37:01,111 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:01,422 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:01,422 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:01,422 DEBUG --- stdout --- 2024-04-10 01:37:01,422 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:01,422 DEBUG --- stderr --- 2024-04-10 01:37:01,422 DEBUG 2024-04-10 01:37:01,422 INFO 2024-04-10 01:37:01,423 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-1000/rc.txt 2024-04-10 01:37:02,259 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 01:37:02,259 DEBUG --- stdout --- 2024-04-10 01:37:02,259 DEBUG 4 /results/orders/authn-users-1000/rc.txt 2024-04-10 01:37:02,259 DEBUG --- stderr --- 2024-04-10 01:37:02,259 DEBUG 2024-04-10 01:37:02,259 INFO - Download file (size 4k) 2024-04-10 01:37:02,260 INFO 2024-04-10 01:37:02,260 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-10 01:37:02,260 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:02,590 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:02,590 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:02,590 DEBUG --- stdout --- 2024-04-10 01:37:02,590 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:02,590 DEBUG --- stderr --- 2024-04-10 01:37:02,590 DEBUG source : /results/orders/authn-users-1000/rc.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/rc.txt 2024-04-10 01:37:02,591 INFO 2024-04-10 01:37:02,591 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-10 01:37:02,591 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:02,916 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:02,917 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:02,917 DEBUG --- stdout --- 2024-04-10 01:37:02,917 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:02,917 DEBUG --- stderr --- 2024-04-10 01:37:02,917 DEBUG 2024-04-10 01:37:02,918 INFO 2024-04-10 01:37:02,918 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-1000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/rc.txt 2024-04-10 01:37:02,918 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 01:37:03,684 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:03,684 DEBUG --- stdout --- 2024-04-10 01:37:03,684 DEBUG tar: Removing leading `/' from member names 2024-04-10 01:37:03,684 DEBUG --- stderr --- 2024-04-10 01:37:03,684 DEBUG 2024-04-10 01:37:03,685 INFO 2024-04-10 01:37:03,685 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-10 01:37:03,685 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:03,998 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:03,998 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:03,998 DEBUG --- stdout --- 2024-04-10 01:37:03,998 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:03,998 DEBUG --- stderr --- 2024-04-10 01:37:03,998 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 01:37:03,999 INFO 2024-04-10 01:37:03,999 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 01:37:03,999 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:04,304 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:04,304 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:04,304 DEBUG --- stdout --- 2024-04-10 01:37:04,304 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:04,304 DEBUG --- stderr --- 2024-04-10 01:37:04,304 DEBUG 2024-04-10 01:37:04,305 INFO 2024-04-10 01:37:04,305 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-1000/stderr.txt" ]; then echo "/results/orders/authn-users-1000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-1000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-1000/stderr.txt file found" 2024-04-10 01:37:04,305 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 01:37:05,161 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 01:37:05,161 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:05,161 DEBUG --- stdout --- 2024-04-10 01:37:05,161 DEBUG /results/orders/authn-users-1000/stderr.txt file found 2024-04-10 01:37:05,162 DEBUG --- stderr --- 2024-04-10 01:37:05,162 DEBUG 2024-04-10 01:37:05,162 INFO 2024-04-10 01:37:05,162 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-10 01:37:05,162 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:05,489 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:05,489 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:05,489 DEBUG --- stdout --- 2024-04-10 01:37:05,489 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:05,489 DEBUG --- stderr --- 2024-04-10 01:37:05,489 DEBUG 2024-04-10 01:37:05,489 INFO 2024-04-10 01:37:05,490 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-1000/stderr.txt 2024-04-10 01:37:06,340 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 01:37:06,340 DEBUG --- stdout --- 2024-04-10 01:37:06,340 DEBUG 0 /results/orders/authn-users-1000/stderr.txt 2024-04-10 01:37:06,340 DEBUG --- stderr --- 2024-04-10 01:37:06,340 DEBUG 2024-04-10 01:37:06,340 INFO - Download file (size 0k) 2024-04-10 01:37:06,341 INFO 2024-04-10 01:37:06,341 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-10 01:37:06,341 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:06,657 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:06,657 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:06,657 DEBUG --- stdout --- 2024-04-10 01:37:06,657 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:06,658 DEBUG --- stderr --- 2024-04-10 01:37:06,658 DEBUG source : /results/orders/authn-users-1000/stderr.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/stderr.txt 2024-04-10 01:37:06,658 INFO 2024-04-10 01:37:06,658 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-10 01:37:06,658 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:06,994 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:06,994 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:06,994 DEBUG --- stdout --- 2024-04-10 01:37:06,994 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:06,994 DEBUG --- stderr --- 2024-04-10 01:37:06,994 DEBUG 2024-04-10 01:37:06,995 INFO 2024-04-10 01:37:06,995 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-1000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/stderr.txt 2024-04-10 01:37:06,995 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 01:37:07,786 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:07,787 DEBUG --- stdout --- 2024-04-10 01:37:07,787 DEBUG tar: Removing leading `/' from member names 2024-04-10 01:37:07,787 DEBUG --- stderr --- 2024-04-10 01:37:07,787 DEBUG 2024-04-10 01:37:07,787 INFO 2024-04-10 01:37:07,787 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-10 01:37:07,787 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:08,108 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:08,109 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:08,110 DEBUG --- stdout --- 2024-04-10 01:37:08,110 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:08,110 DEBUG --- stderr --- 2024-04-10 01:37:08,110 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 01:37:08,110 INFO 2024-04-10 01:37:08,110 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-10 01:37:08,110 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:08,438 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:08,438 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:08,438 DEBUG --- stdout --- 2024-04-10 01:37:08,438 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:08,438 DEBUG --- stderr --- 2024-04-10 01:37:08,438 DEBUG 2024-04-10 01:37:08,439 INFO 2024-04-10 01:37:08,439 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-1000/stdout.txt" ]; then echo "/results/orders/authn-users-1000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-1000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-1000/stdout.txt file found" 2024-04-10 01:37:08,439 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 01:37:09,284 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 01:37:09,284 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:09,284 DEBUG --- stdout --- 2024-04-10 01:37:09,284 DEBUG /results/orders/authn-users-1000/stdout.txt file found 2024-04-10 01:37:09,285 DEBUG --- stderr --- 2024-04-10 01:37:09,285 DEBUG 2024-04-10 01:37:09,285 INFO 2024-04-10 01:37:09,285 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-10 01:37:09,285 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:09,627 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:09,628 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:09,628 DEBUG --- stdout --- 2024-04-10 01:37:09,628 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:09,628 DEBUG --- stderr --- 2024-04-10 01:37:09,628 DEBUG 2024-04-10 01:37:09,628 INFO 2024-04-10 01:37:09,628 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-1000/stdout.txt 2024-04-10 01:37:10,458 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 01:37:10,458 DEBUG --- stdout --- 2024-04-10 01:37:10,458 DEBUG 504 /results/orders/authn-users-1000/stdout.txt 2024-04-10 01:37:10,458 DEBUG --- stderr --- 2024-04-10 01:37:10,458 DEBUG 2024-04-10 01:37:10,458 INFO - Download file (size 504k) 2024-04-10 01:37:10,459 INFO 2024-04-10 01:37:10,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-10 01:37:10,459 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:10,780 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:10,780 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:10,780 DEBUG --- stdout --- 2024-04-10 01:37:10,780 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:10,781 DEBUG --- stderr --- 2024-04-10 01:37:10,781 DEBUG source : /results/orders/authn-users-1000/stdout.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/stdout.txt 2024-04-10 01:37:10,781 INFO 2024-04-10 01:37:10,781 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-10 01:37:10,781 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:11,107 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:11,107 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:11,107 DEBUG --- stdout --- 2024-04-10 01:37:11,107 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:11,107 DEBUG --- stderr --- 2024-04-10 01:37:11,107 DEBUG 2024-04-10 01:37:11,108 INFO 2024-04-10 01:37:11,108 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-1000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/stdout.txt 2024-04-10 01:37:11,108 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 01:37:12,195 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:12,196 DEBUG --- stdout --- 2024-04-10 01:37:12,196 DEBUG tar: Removing leading `/' from member names 2024-04-10 01:37:12,196 DEBUG --- stderr --- 2024-04-10 01:37:12,196 DEBUG ________________________________________________________________________________ [2024-04-10 01:37:12] authn-users-1000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-10 01:37:12,197 INFO 2024-04-10 01:37:12,197 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-10 01:37:12,197 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:12,518 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:12,518 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:12,518 DEBUG --- stdout --- 2024-04-10 01:37:12,518 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:12,518 DEBUG --- stderr --- 2024-04-10 01:37:12,518 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 01:37:12,518 INFO 2024-04-10 01:37:12,518 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-10 01:37:12,519 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:12,845 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:12,845 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:12,845 DEBUG --- stdout --- 2024-04-10 01:37:12,845 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:12,846 DEBUG --- stderr --- 2024-04-10 01:37:12,846 DEBUG 2024-04-10 01:37:12,846 INFO 2024-04-10 01:37:12,846 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-1000/cmd.txt" ]; then echo "/results/orders/authn-users-1000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-1000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-1000/cmd.txt file found" 2024-04-10 01:37:12,846 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 01:37:13,693 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 01:37:13,693 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:13,693 DEBUG --- stdout --- 2024-04-10 01:37:13,693 DEBUG /results/orders/authn-users-1000/cmd.txt file found 2024-04-10 01:37:13,693 DEBUG --- stderr --- 2024-04-10 01:37:13,693 DEBUG 2024-04-10 01:37:13,694 INFO 2024-04-10 01:37:13,694 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-10 01:37:13,694 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:14,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:14,029 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:14,029 DEBUG --- stdout --- 2024-04-10 01:37:14,029 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:14,029 DEBUG --- stderr --- 2024-04-10 01:37:14,029 DEBUG 2024-04-10 01:37:14,029 INFO 2024-04-10 01:37:14,030 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-1000/cmd.txt 2024-04-10 01:37:14,844 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 01:37:14,845 DEBUG --- stdout --- 2024-04-10 01:37:14,845 DEBUG 4 /results/orders/authn-users-1000/cmd.txt 2024-04-10 01:37:14,845 DEBUG --- stderr --- 2024-04-10 01:37:14,845 DEBUG 2024-04-10 01:37:14,845 INFO - Download file (size 4k) 2024-04-10 01:37:14,846 INFO 2024-04-10 01:37:14,846 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-10 01:37:14,846 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:15,181 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:15,181 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:15,181 DEBUG --- stdout --- 2024-04-10 01:37:15,181 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:15,181 DEBUG --- stderr --- 2024-04-10 01:37:15,181 DEBUG source : /results/orders/authn-users-1000/cmd.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/cmd.txt 2024-04-10 01:37:15,182 INFO 2024-04-10 01:37:15,182 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-10 01:37:15,182 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 01:37:15,517 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 01:37:15,517 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:15,517 DEBUG --- stdout --- 2024-04-10 01:37:15,517 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 01:37:15,517 DEBUG --- stderr --- 2024-04-10 01:37:15,517 DEBUG 2024-04-10 01:37:15,517 INFO 2024-04-10 01:37:15,517 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-1000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/cmd.txt 2024-04-10 01:37:15,517 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 01:37:16,285 INFO [loop_until]: OK (rc = 0) 2024-04-10 01:37:16,285 DEBUG --- stdout --- 2024-04-10 01:37:16,285 DEBUG tar: Removing leading `/' from member names 2024-04-10 01:37:16,285 DEBUG --- stderr --- 2024-04-10 01:37:16,285 DEBUG 2024-04-10 01:37:16,286 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/cmd.txt is small (1 lines), printing all lines: 2024-04-10 01:37:16,286 DEBUG ----- output ----- 2024-04-10 01:37:16,286 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-1000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-10 01:37:16,286 INFO ________________________________________________________________________________ [2024-04-10 01:37:16] authn-users-1000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-10 01:37:16,286 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/rc.txt is small (1 lines), printing all lines: 2024-04-10 01:37:16,286 DEBUG ----- output ----- 2024-04-10 01:37:16,286 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-10 01:37:16,286 INFO ________________________________________________________________________________ [2024-04-10 01:37:16] authn-users-1000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-10 01:37:16,288 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/stdout.txt is 8734 lines): 2024-04-10 01:37:16,289 DEBUG ----- output ----- 2024-04-10 01:37:16,289 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-10 01:37:16,289 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-10 01:37:16,289 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-10 01:37:16,289 INFO 2024-04-10 01:37:16,289 INFO ================================================================================ 2024-04-10 01:37:16,289 INFO 2024-04-10 01:34:52 5s elapsed 2024-04-10 01:37:16,289 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,289 INFO > Global (OK=416 KO=0 ) 2024-04-10 01:37:16,289 INFO > userRestLogin (OK=416 KO=0 ) 2024-04-10 01:37:16,289 INFO 2024-04-10 01:37:16,289 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,289 INFO [- ] 0% 2024-04-10 01:37:16,289 INFO waiting: 3615466 / active: 3 / done: 416 2024-04-10 01:37:16,289 INFO ================================================================================ 2024-04-10 01:37:16,289 INFO 2024-04-10 01:37:16,289 INFO 2024-04-10 01:37:16,289 INFO ================================================================================ 2024-04-10 01:37:16,289 INFO 2024-04-10 01:34:57 10s elapsed 2024-04-10 01:37:16,289 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,289 INFO > Global (OK=1668 KO=0 ) 2024-04-10 01:37:16,289 INFO > userRestLogin (OK=1668 KO=0 ) 2024-04-10 01:37:16,289 INFO 2024-04-10 01:37:16,289 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,289 INFO [- ] 0% 2024-04-10 01:37:16,289 INFO waiting: 3614214 / active: 3 / done: 1668 2024-04-10 01:37:16,289 INFO ================================================================================ 2024-04-10 01:37:16,289 INFO 2024-04-10 01:37:16,289 INFO 2024-04-10 01:37:16,290 INFO ================================================================================ 2024-04-10 01:37:16,290 INFO 2024-04-10 01:35:02 15s elapsed 2024-04-10 01:37:16,290 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,290 INFO > Global (OK=3752 KO=0 ) 2024-04-10 01:37:16,290 INFO > userRestLogin (OK=3752 KO=0 ) 2024-04-10 01:37:16,290 INFO 2024-04-10 01:37:16,290 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,290 INFO [- ] 0% 2024-04-10 01:37:16,290 INFO waiting: 3612127 / active: 6 / done: 3752 2024-04-10 01:37:16,290 INFO ================================================================================ 2024-04-10 01:37:16,290 INFO 2024-04-10 01:37:16,290 INFO 2024-04-10 01:37:16,290 INFO ================================================================================ 2024-04-10 01:37:16,290 INFO 2024-04-10 01:35:07 20s elapsed 2024-04-10 01:37:16,290 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,290 INFO > Global (OK=6669 KO=0 ) 2024-04-10 01:37:16,290 INFO > userRestLogin (OK=6669 KO=0 ) 2024-04-10 01:37:16,290 INFO 2024-04-10 01:37:16,290 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,290 INFO [- ] 0% 2024-04-10 01:37:16,290 INFO waiting: 3609209 / active: 7 / done: 6669 2024-04-10 01:37:16,290 INFO ================================================================================ 2024-04-10 01:37:16,290 INFO 2024-04-10 01:37:16,290 INFO 2024-04-10 01:37:16,290 INFO ================================================================================ 2024-04-10 01:37:16,290 INFO 2024-04-10 01:35:12 25s elapsed 2024-04-10 01:37:16,290 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,290 INFO > Global (OK=10420 KO=0 ) 2024-04-10 01:37:16,290 INFO > userRestLogin (OK=10420 KO=0 ) 2024-04-10 01:37:16,291 INFO 2024-04-10 01:37:16,291 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,291 INFO [- ] 0% 2024-04-10 01:37:16,291 INFO waiting: 3605457 / active: 9 / done: 10419 2024-04-10 01:37:16,291 INFO ================================================================================ 2024-04-10 01:37:16,291 INFO 2024-04-10 01:37:16,291 INFO 2024-04-10 01:37:16,291 INFO ================================================================================ 2024-04-10 01:37:16,291 INFO 2024-04-10 01:35:17 30s elapsed 2024-04-10 01:37:16,291 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,291 INFO > Global (OK=14982 KO=0 ) 2024-04-10 01:37:16,291 INFO > userRestLogin (OK=14982 KO=0 ) 2024-04-10 01:37:16,291 INFO 2024-04-10 01:37:16,291 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,291 INFO [- ] 0% 2024-04-10 01:37:16,291 INFO waiting: 3600880 / active: 23 / done: 14982 2024-04-10 01:37:16,291 INFO ================================================================================ 2024-04-10 01:37:16,291 INFO 2024-04-10 01:37:16,291 INFO 2024-04-10 01:37:16,291 INFO ================================================================================ 2024-04-10 01:37:16,291 INFO 2024-04-10 01:35:22 35s elapsed 2024-04-10 01:37:16,291 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,291 INFO > Global (OK=19967 KO=0 ) 2024-04-10 01:37:16,291 INFO > userRestLogin (OK=19967 KO=0 ) 2024-04-10 01:37:16,291 INFO 2024-04-10 01:37:16,291 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,291 INFO [- ] 0% 2024-04-10 01:37:16,291 INFO waiting: 3595912 / active: 6 / done: 19967 2024-04-10 01:37:16,291 INFO ================================================================================ 2024-04-10 01:37:16,292 INFO 2024-04-10 01:37:16,292 INFO 2024-04-10 01:37:16,292 INFO ================================================================================ 2024-04-10 01:37:16,292 INFO 2024-04-10 01:35:27 40s elapsed 2024-04-10 01:37:16,292 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,292 INFO > Global (OK=24923 KO=0 ) 2024-04-10 01:37:16,292 INFO > userRestLogin (OK=24923 KO=0 ) 2024-04-10 01:37:16,292 INFO 2024-04-10 01:37:16,292 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,292 INFO [- ] 0% 2024-04-10 01:37:16,292 INFO waiting: 3590956 / active: 6 / done: 24923 2024-04-10 01:37:16,292 INFO ================================================================================ 2024-04-10 01:37:16,292 INFO 2024-04-10 01:37:16,292 INFO 2024-04-10 01:37:16,292 INFO [...] 2024-04-10 01:37:16,295 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,295 INFO > Global (OK=3585551 KO=0 ) 2024-04-10 01:37:16,295 INFO > userRestLogin (OK=3585551 KO=0 ) 2024-04-10 01:37:16,295 INFO 2024-04-10 01:37:16,295 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,295 INFO [#########################################################################-] 99% 2024-04-10 01:37:16,295 INFO waiting: 30331 / active: 3 / done: 3585551 2024-04-10 01:37:16,295 INFO ================================================================================ 2024-04-10 01:37:16,295 INFO 2024-04-10 01:37:16,295 INFO 2024-04-10 01:37:16,295 INFO ================================================================================ 2024-04-10 01:37:16,295 INFO 2024-04-10 02:34:52 3605s elapsed 2024-04-10 01:37:16,295 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,295 INFO > Global (OK=3590675 KO=0 ) 2024-04-10 01:37:16,295 INFO > userRestLogin (OK=3590675 KO=0 ) 2024-04-10 01:37:16,295 INFO 2024-04-10 01:37:16,295 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,295 INFO [#########################################################################-] 99% 2024-04-10 01:37:16,295 INFO waiting: 25193 / active: 17 / done: 3590675 2024-04-10 01:37:16,295 INFO ================================================================================ 2024-04-10 01:37:16,295 INFO 2024-04-10 01:37:16,295 INFO 2024-04-10 01:37:16,295 INFO ================================================================================ 2024-04-10 01:37:16,295 INFO 2024-04-10 02:34:57 3610s elapsed 2024-04-10 01:37:16,296 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,296 INFO > Global (OK=3595727 KO=0 ) 2024-04-10 01:37:16,296 INFO > userRestLogin (OK=3595727 KO=0 ) 2024-04-10 01:37:16,296 INFO 2024-04-10 01:37:16,296 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,296 INFO [#########################################################################-] 99% 2024-04-10 01:37:16,296 INFO waiting: 20144 / active: 14 / done: 3595727 2024-04-10 01:37:16,296 INFO ================================================================================ 2024-04-10 01:37:16,296 INFO 2024-04-10 01:37:16,296 INFO 2024-04-10 01:37:16,296 INFO ================================================================================ 2024-04-10 01:37:16,296 INFO 2024-04-10 02:35:02 3615s elapsed 2024-04-10 01:37:16,296 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,296 INFO > Global (OK=3600777 KO=0 ) 2024-04-10 01:37:16,296 INFO > userRestLogin (OK=3600777 KO=0 ) 2024-04-10 01:37:16,296 INFO 2024-04-10 01:37:16,296 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,296 INFO [#########################################################################-] 99% 2024-04-10 01:37:16,296 INFO waiting: 15098 / active: 10 / done: 3600777 2024-04-10 01:37:16,296 INFO ================================================================================ 2024-04-10 01:37:16,296 INFO 2024-04-10 01:37:16,296 INFO 2024-04-10 01:37:16,296 INFO ================================================================================ 2024-04-10 01:37:16,296 INFO 2024-04-10 02:35:07 3620s elapsed 2024-04-10 01:37:16,296 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,296 INFO > Global (OK=3605806 KO=0 ) 2024-04-10 01:37:16,296 INFO > userRestLogin (OK=3605806 KO=0 ) 2024-04-10 01:37:16,296 INFO 2024-04-10 01:37:16,296 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,296 INFO [#########################################################################-] 99% 2024-04-10 01:37:16,296 INFO waiting: 10064 / active: 16 / done: 3605805 2024-04-10 01:37:16,296 INFO ================================================================================ 2024-04-10 01:37:16,296 INFO 2024-04-10 01:37:16,296 INFO 2024-04-10 01:37:16,296 INFO ================================================================================ 2024-04-10 01:37:16,296 INFO 2024-04-10 02:35:12 3625s elapsed 2024-04-10 01:37:16,296 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,297 INFO > Global (OK=3610795 KO=0 ) 2024-04-10 01:37:16,297 INFO > userRestLogin (OK=3610795 KO=0 ) 2024-04-10 01:37:16,297 INFO 2024-04-10 01:37:16,297 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,297 INFO [#########################################################################-] 99% 2024-04-10 01:37:16,297 INFO waiting: 5068 / active: 23 / done: 3610794 2024-04-10 01:37:16,297 INFO ================================================================================ 2024-04-10 01:37:16,297 INFO 2024-04-10 01:37:16,297 INFO 2024-04-10 01:37:16,297 INFO ================================================================================ 2024-04-10 01:37:16,297 INFO 2024-04-10 02:35:17 3630s elapsed 2024-04-10 01:37:16,297 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 01:37:16,297 INFO > Global (OK=3615885 KO=0 ) 2024-04-10 01:37:16,297 INFO > userRestLogin (OK=3615885 KO=0 ) 2024-04-10 01:37:16,297 INFO 2024-04-10 01:37:16,297 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 01:37:16,297 INFO [##########################################################################]100% 2024-04-10 01:37:16,297 INFO waiting: 0 / active: 0 / done: 3615885 2024-04-10 01:37:16,297 INFO ================================================================================ 2024-04-10 01:37:16,297 INFO 2024-04-10 01:37:16,297 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-10 01:37:16,297 INFO 2024-04-10 01:37:16,297 INFO ---------------------- 2024-04-10 01:37:16,297 INFO [10/Apr/2024 02:35:17] Create tar file authn-users-1000.tar.gz 2024-04-10 01:37:16,297 INFO ---------------------- 2024-04-10 01:37:16,297 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-10 01:37:16,297 INFO RESULT_FOLDER=authn-users-1000 2024-04-10 01:37:16,297 INFO SIMULATION_PATH=authn-users-1000/loadtest-20240410003446484 2024-04-10 01:37:16,297 INFO PWD=/results/gatling/authn-users-1000/loadtest-20240410003446484 2024-04-10 01:37:16,297 INFO [cmd] tar -czvf /results/gatling//authn-users-1000.tar.gz --exclude=simulation.log * 2024-04-10 01:37:16,297 INFO /results/gatling 2024-04-10 01:37:16,297 INFO 2024-04-10 01:37:16,297 INFO ---------------------- 2024-04-10 01:37:16,297 INFO [10/Apr/2024 02:35:17] Update /results/gatling//latest 2024-04-10 01:37:16,297 INFO ---------------------- 2024-04-10 01:37:16,297 INFO 2024-04-10 01:37:16,297 INFO ---------------------- 2024-04-10 01:37:16,297 INFO [10/Apr/2024 02:35:17] Update /results/gatling//latest.tar.gz 2024-04-10 01:37:16,297 INFO ---------------------- 2024-04-10 01:37:16,298 INFO ________________________________________________________________________________ [2024-04-10 01:37:16] authn-users-1000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-10 01:37:16,298 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-1000/stderr.txt is small (0 lines), printing all lines: 2024-04-10 01:37:16,298 DEBUG ----- output ----- 2024-04-10 01:37:16,298 DEBUG 2024-04-10 01:37:16,298 INFO ________________________________________________________________________________ [2024-04-10 01:37:16] authn-users-1000 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-10 01:37:16] authn-users-1000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-10 01:37:16] authn-users-1000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped