--Task-- name: authn-users-9000 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['authn-users-6000'] preceding_task: authn-users-6000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 9000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-22 01:21:49] authn-users-9000 pre : N/A ________________________________________________________________________________ [2024-04-22 01:21:49] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-22 01:21:49] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-9000/stdout.txt Pod log : /results/orders/authn-users-9000 2024-04-22 01:21:49,398 INFO 2024-04-22 01:21:49,398 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-22 01:21:49,399 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:49,725 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:49,725 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:49,725 DEBUG --- stdout --- 2024-04-22 01:21:49,725 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:49,726 DEBUG --- stderr --- 2024-04-22 01:21:49,726 DEBUG ________________________________________________________________________________ [2024-04-22 01:21:49] authn-users-9000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-22 01:21:49,726 INFO 2024-04-22 01:21:49,726 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-22 01:21:49,726 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:50,050 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:50,051 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:50,051 DEBUG --- stdout --- 2024-04-22 01:21:50,051 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:50,051 DEBUG --- stderr --- 2024-04-22 01:21:50,051 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-9000 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-22 01:21:50] authn-users-9000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-22 01:21:50,051 INFO 2024-04-22 01:21:50,051 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 01:21:50,051 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:50,368 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:50,368 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:50,368 DEBUG --- stdout --- 2024-04-22 01:21:50,368 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:50,368 DEBUG --- stderr --- 2024-04-22 01:21:50,368 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/order.json { "name": "authn-users-9000", "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-9000 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/authn-users-9000", "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=9000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-22 01:21:50] authn-users-9000 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-22 01:21:50,370 INFO 2024-04-22 01:21:50,370 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-22 01:21:50,370 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-22 01:21:50,602 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 01:21:50,602 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:50,603 DEBUG --- stdout --- 2024-04-22 01:21:50,603 DEBUG WAITING 2024-04-22 01:21:50,603 DEBUG --- stderr --- 2024-04-22 01:21:50,603 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-22 01:21:50] authn-users-9000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-22 01:21:50,603 INFO 2024-04-22 01:21:50,603 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-22 01:21:50,603 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:50,914 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:50,914 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:50,914 DEBUG --- stdout --- 2024-04-22 01:21:50,915 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:50,915 DEBUG --- stderr --- 2024-04-22 01:21:50,915 DEBUG 2024-04-22 01:21:50,915 INFO 2024-04-22 01:21:50,915 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/order.json overseer-0-68c5d5b884-p97w5:/results/orders/order.json 2024-04-22 01:21:50,915 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 01:21:52,283 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:52,283 DEBUG --- stdout --- 2024-04-22 01:21:52,283 DEBUG 2024-04-22 01:21:52,283 DEBUG --- stderr --- 2024-04-22 01:21:52,283 DEBUG Order has been successfully forwarded to overseer [2024-04-22 01:21:52] - INFO: Check if Gatling simulation started successfully 2024-04-22 01:21:52,284 INFO 2024-04-22 01:21:52,284 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-22 01:21:52,284 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:52,606 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:52,607 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:52,607 DEBUG --- stdout --- 2024-04-22 01:21:52,607 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:52,607 DEBUG --- stderr --- 2024-04-22 01:21:52,607 DEBUG 2024-04-22 01:21:52,607 INFO 2024-04-22 01:21:52,607 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 -- head -n 25 /results/orders/authn-users-9000/stdout.txt | grep "started..." 2024-04-22 01:21:52,607 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-22 01:21:53,365 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-22 01:21:59,182 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-22 01:22:04,964 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-22 01:22:04,964 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:22:04,964 DEBUG --- stdout --- 2024-04-22 01:22:04,964 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-22 01:22:04,964 DEBUG --- stderr --- 2024-04-22 01:22:04,965 DEBUG ________________________________________________________________________________ [2024-04-22 01:22:04] authn-users-9000 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-22 02:24:15] authn-users-9000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-22 02:24:15,591 INFO 2024-04-22 02:24:15,592 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-22 02:24:15,592 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:15,914 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:15,914 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:15,914 DEBUG --- stdout --- 2024-04-22 02:24:15,914 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:15,914 DEBUG --- stderr --- 2024-04-22 02:24:15,914 DEBUG 2024-04-22 02:24:15,915 INFO 2024-04-22 02:24:15,915 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 -- rm -f /results/orders/order.json 2024-04-22 02:24:15,915 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 02:24:16,703 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:16,703 DEBUG --- stdout --- 2024-04-22 02:24:16,703 DEBUG 2024-04-22 02:24:16,703 DEBUG --- stderr --- 2024-04-22 02:24:16,703 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-22 02:24:16] authn-users-9000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-22 02:24:16] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/order.json 2024-04-22 02:24:16,704 INFO 2024-04-22 02:24:16,704 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-22 02:24:16,704 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:17,012 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:17,012 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:17,012 DEBUG --- stdout --- 2024-04-22 02:24:17,012 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:17,012 DEBUG --- stderr --- 2024-04-22 02:24:17,012 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 02:24:17,013 INFO 2024-04-22 02:24:17,013 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-22 02:24:17,013 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:17,314 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:17,315 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:17,315 DEBUG --- stdout --- 2024-04-22 02:24:17,315 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:17,315 DEBUG --- stderr --- 2024-04-22 02:24:17,315 DEBUG 2024-04-22 02:24:17,315 INFO 2024-04-22 02:24:17,315 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-9000/rc.txt" ]; then echo "/results/orders/authn-users-9000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-9000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-9000/rc.txt file found" 2024-04-22 02:24:17,315 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 02:24:18,136 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 02:24:18,136 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:18,136 DEBUG --- stdout --- 2024-04-22 02:24:18,136 DEBUG /results/orders/authn-users-9000/rc.txt file found 2024-04-22 02:24:18,136 DEBUG --- stderr --- 2024-04-22 02:24:18,136 DEBUG 2024-04-22 02:24:18,137 INFO 2024-04-22 02:24:18,137 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-22 02:24:18,137 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:18,441 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:18,441 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:18,441 DEBUG --- stdout --- 2024-04-22 02:24:18,441 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:18,441 DEBUG --- stderr --- 2024-04-22 02:24:18,441 DEBUG 2024-04-22 02:24:18,441 INFO 2024-04-22 02:24:18,442 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-9000/rc.txt 2024-04-22 02:24:19,261 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 02:24:19,262 DEBUG --- stdout --- 2024-04-22 02:24:19,262 DEBUG 4 /results/orders/authn-users-9000/rc.txt 2024-04-22 02:24:19,263 DEBUG --- stderr --- 2024-04-22 02:24:19,263 DEBUG 2024-04-22 02:24:19,263 INFO - Download file (size 4k) 2024-04-22 02:24:19,263 INFO 2024-04-22 02:24:19,263 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-22 02:24:19,264 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:19,584 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:19,584 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:19,584 DEBUG --- stdout --- 2024-04-22 02:24:19,584 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:19,585 DEBUG --- stderr --- 2024-04-22 02:24:19,585 DEBUG source : /results/orders/authn-users-9000/rc.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/rc.txt 2024-04-22 02:24:19,585 INFO 2024-04-22 02:24:19,585 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-22 02:24:19,585 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:19,898 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:19,898 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:19,898 DEBUG --- stdout --- 2024-04-22 02:24:19,898 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:19,898 DEBUG --- stderr --- 2024-04-22 02:24:19,898 DEBUG 2024-04-22 02:24:19,899 INFO 2024-04-22 02:24:19,899 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-9000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/rc.txt 2024-04-22 02:24:19,899 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 02:24:20,649 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:20,649 DEBUG --- stdout --- 2024-04-22 02:24:20,649 DEBUG tar: Removing leading `/' from member names 2024-04-22 02:24:20,649 DEBUG --- stderr --- 2024-04-22 02:24:20,649 DEBUG 2024-04-22 02:24:20,649 INFO 2024-04-22 02:24:20,649 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-22 02:24:20,649 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:20,950 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:20,950 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:20,950 DEBUG --- stdout --- 2024-04-22 02:24:20,950 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:20,950 DEBUG --- stderr --- 2024-04-22 02:24:20,950 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 02:24:20,950 INFO 2024-04-22 02:24:20,951 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-22 02:24:20,951 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:21,253 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:21,253 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:21,253 DEBUG --- stdout --- 2024-04-22 02:24:21,253 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:21,254 DEBUG --- stderr --- 2024-04-22 02:24:21,254 DEBUG 2024-04-22 02:24:21,254 INFO 2024-04-22 02:24:21,254 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-9000/stderr.txt" ]; then echo "/results/orders/authn-users-9000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-9000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-9000/stderr.txt file found" 2024-04-22 02:24:21,254 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 02:24:22,079 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 02:24:22,080 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:22,080 DEBUG --- stdout --- 2024-04-22 02:24:22,080 DEBUG /results/orders/authn-users-9000/stderr.txt file found 2024-04-22 02:24:22,080 DEBUG --- stderr --- 2024-04-22 02:24:22,080 DEBUG 2024-04-22 02:24:22,080 INFO 2024-04-22 02:24:22,080 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-22 02:24:22,080 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:22,390 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:22,390 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:22,390 DEBUG --- stdout --- 2024-04-22 02:24:22,390 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:22,390 DEBUG --- stderr --- 2024-04-22 02:24:22,390 DEBUG 2024-04-22 02:24:22,390 INFO 2024-04-22 02:24:22,390 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-9000/stderr.txt 2024-04-22 02:24:23,206 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 02:24:23,207 DEBUG --- stdout --- 2024-04-22 02:24:23,207 DEBUG 0 /results/orders/authn-users-9000/stderr.txt 2024-04-22 02:24:23,207 DEBUG --- stderr --- 2024-04-22 02:24:23,207 DEBUG 2024-04-22 02:24:23,207 INFO - Download file (size 0k) 2024-04-22 02:24:23,207 INFO 2024-04-22 02:24:23,207 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-22 02:24:23,207 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:23,562 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:23,562 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:23,562 DEBUG --- stdout --- 2024-04-22 02:24:23,562 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:23,562 DEBUG --- stderr --- 2024-04-22 02:24:23,562 DEBUG source : /results/orders/authn-users-9000/stderr.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/stderr.txt 2024-04-22 02:24:23,563 INFO 2024-04-22 02:24:23,563 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-22 02:24:23,563 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:23,877 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:23,877 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:23,877 DEBUG --- stdout --- 2024-04-22 02:24:23,877 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:23,877 DEBUG --- stderr --- 2024-04-22 02:24:23,877 DEBUG 2024-04-22 02:24:23,878 INFO 2024-04-22 02:24:23,878 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-9000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/stderr.txt 2024-04-22 02:24:23,878 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 02:24:24,617 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:24,617 DEBUG --- stdout --- 2024-04-22 02:24:24,617 DEBUG tar: Removing leading `/' from member names 2024-04-22 02:24:24,617 DEBUG --- stderr --- 2024-04-22 02:24:24,617 DEBUG 2024-04-22 02:24:24,618 INFO 2024-04-22 02:24:24,618 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-22 02:24:24,618 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:24,930 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:24,931 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:24,931 DEBUG --- stdout --- 2024-04-22 02:24:24,931 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:24,931 DEBUG --- stderr --- 2024-04-22 02:24:24,931 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 02:24:24,931 INFO 2024-04-22 02:24:24,931 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-22 02:24:24,931 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:25,249 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:25,249 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:25,249 DEBUG --- stdout --- 2024-04-22 02:24:25,249 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:25,249 DEBUG --- stderr --- 2024-04-22 02:24:25,249 DEBUG 2024-04-22 02:24:25,249 INFO 2024-04-22 02:24:25,249 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-9000/stdout.txt" ]; then echo "/results/orders/authn-users-9000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-9000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-9000/stdout.txt file found" 2024-04-22 02:24:25,250 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 02:24:26,084 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 02:24:26,085 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:26,085 DEBUG --- stdout --- 2024-04-22 02:24:26,085 DEBUG /results/orders/authn-users-9000/stdout.txt file found 2024-04-22 02:24:26,085 DEBUG --- stderr --- 2024-04-22 02:24:26,085 DEBUG 2024-04-22 02:24:26,085 INFO 2024-04-22 02:24:26,085 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-22 02:24:26,085 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:26,388 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:26,389 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:26,389 DEBUG --- stdout --- 2024-04-22 02:24:26,389 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:26,389 DEBUG --- stderr --- 2024-04-22 02:24:26,389 DEBUG 2024-04-22 02:24:26,389 INFO 2024-04-22 02:24:26,389 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-9000/stdout.txt 2024-04-22 02:24:27,213 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 02:24:27,213 DEBUG --- stdout --- 2024-04-22 02:24:27,214 DEBUG 508 /results/orders/authn-users-9000/stdout.txt 2024-04-22 02:24:27,214 DEBUG --- stderr --- 2024-04-22 02:24:27,214 DEBUG 2024-04-22 02:24:27,214 INFO - Download file (size 508k) 2024-04-22 02:24:27,214 INFO 2024-04-22 02:24:27,214 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-22 02:24:27,214 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:27,515 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:27,515 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:27,515 DEBUG --- stdout --- 2024-04-22 02:24:27,515 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:27,515 DEBUG --- stderr --- 2024-04-22 02:24:27,515 DEBUG source : /results/orders/authn-users-9000/stdout.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/stdout.txt 2024-04-22 02:24:27,516 INFO 2024-04-22 02:24:27,516 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-22 02:24:27,516 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:27,820 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:27,820 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:27,820 DEBUG --- stdout --- 2024-04-22 02:24:27,820 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:27,821 DEBUG --- stderr --- 2024-04-22 02:24:27,821 DEBUG 2024-04-22 02:24:27,821 INFO 2024-04-22 02:24:27,821 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-9000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/stdout.txt 2024-04-22 02:24:27,821 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 02:24:28,887 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:28,887 DEBUG --- stdout --- 2024-04-22 02:24:28,887 DEBUG tar: Removing leading `/' from member names 2024-04-22 02:24:28,887 DEBUG --- stderr --- 2024-04-22 02:24:28,887 DEBUG ________________________________________________________________________________ [2024-04-22 02:24:28] authn-users-9000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-22 02:24:28,888 INFO 2024-04-22 02:24:28,888 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-22 02:24:28,888 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:29,189 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:29,189 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:29,189 DEBUG --- stdout --- 2024-04-22 02:24:29,189 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:29,189 DEBUG --- stderr --- 2024-04-22 02:24:29,189 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 02:24:29,189 INFO 2024-04-22 02:24:29,189 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-22 02:24:29,189 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:29,495 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:29,496 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:29,496 DEBUG --- stdout --- 2024-04-22 02:24:29,496 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:29,496 DEBUG --- stderr --- 2024-04-22 02:24:29,496 DEBUG 2024-04-22 02:24:29,496 INFO 2024-04-22 02:24:29,496 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-9000/cmd.txt" ]; then echo "/results/orders/authn-users-9000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-9000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-9000/cmd.txt file found" 2024-04-22 02:24:29,496 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 02:24:30,320 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 02:24:30,322 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:30,322 DEBUG --- stdout --- 2024-04-22 02:24:30,322 DEBUG /results/orders/authn-users-9000/cmd.txt file found 2024-04-22 02:24:30,322 DEBUG --- stderr --- 2024-04-22 02:24:30,322 DEBUG 2024-04-22 02:24:30,322 INFO 2024-04-22 02:24:30,322 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-22 02:24:30,322 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:30,626 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:30,626 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:30,626 DEBUG --- stdout --- 2024-04-22 02:24:30,627 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:30,627 DEBUG --- stderr --- 2024-04-22 02:24:30,627 DEBUG 2024-04-22 02:24:30,627 INFO 2024-04-22 02:24:30,627 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-9000/cmd.txt 2024-04-22 02:24:31,448 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 02:24:31,448 DEBUG --- stdout --- 2024-04-22 02:24:31,448 DEBUG 4 /results/orders/authn-users-9000/cmd.txt 2024-04-22 02:24:31,448 DEBUG --- stderr --- 2024-04-22 02:24:31,448 DEBUG 2024-04-22 02:24:31,448 INFO - Download file (size 4k) 2024-04-22 02:24:31,448 INFO 2024-04-22 02:24:31,448 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-22 02:24:31,449 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:31,748 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:31,748 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:31,748 DEBUG --- stdout --- 2024-04-22 02:24:31,749 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:31,749 DEBUG --- stderr --- 2024-04-22 02:24:31,749 DEBUG source : /results/orders/authn-users-9000/cmd.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/cmd.txt 2024-04-22 02:24:31,749 INFO 2024-04-22 02:24:31,749 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-22 02:24:31,749 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:32,057 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:32,057 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:32,058 DEBUG --- stdout --- 2024-04-22 02:24:32,058 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:32,058 DEBUG --- stderr --- 2024-04-22 02:24:32,058 DEBUG 2024-04-22 02:24:32,058 INFO 2024-04-22 02:24:32,058 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-9000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/cmd.txt 2024-04-22 02:24:32,058 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 02:24:32,802 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:32,802 DEBUG --- stdout --- 2024-04-22 02:24:32,802 DEBUG tar: Removing leading `/' from member names 2024-04-22 02:24:32,802 DEBUG --- stderr --- 2024-04-22 02:24:32,802 DEBUG 2024-04-22 02:24:32,803 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/cmd.txt is small (1 lines), printing all lines: 2024-04-22 02:24:32,803 DEBUG ----- output ----- 2024-04-22 02:24:32,803 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-9000 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-22 02:24:32,803 INFO ________________________________________________________________________________ [2024-04-22 02:24:32] authn-users-9000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-22 02:24:32,803 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/rc.txt is small (1 lines), printing all lines: 2024-04-22 02:24:32,803 DEBUG ----- output ----- 2024-04-22 02:24:32,803 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-22 02:24:32,803 INFO ________________________________________________________________________________ [2024-04-22 02:24:32] authn-users-9000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-22 02:24:32,805 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/stdout.txt is 8746 lines): 2024-04-22 02:24:32,805 DEBUG ----- output ----- 2024-04-22 02:24:32,805 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-22 02:24:32,805 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-22 02:24:32,805 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-22 02:24:32,805 INFO 2024-04-22 02:24:32,805 INFO ================================================================================ 2024-04-22 02:24:32,805 INFO 2024-04-22 02:22:06 5s elapsed 2024-04-22 02:24:32,805 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,805 INFO > Global (OK=3755 KO=0 ) 2024-04-22 02:24:32,805 INFO > userRestLogin (OK=3755 KO=0 ) 2024-04-22 02:24:32,805 INFO 2024-04-22 02:24:32,805 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,805 INFO [- ] 0% 2024-04-22 02:24:32,805 INFO waiting: 32520694 / active: 14 / done: 3755 2024-04-22 02:24:32,805 INFO ================================================================================ 2024-04-22 02:24:32,805 INFO 2024-04-22 02:24:32,806 INFO 2024-04-22 02:24:32,806 INFO ================================================================================ 2024-04-22 02:24:32,806 INFO 2024-04-22 02:22:11 10s elapsed 2024-04-22 02:24:32,806 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,806 INFO > Global (OK=15000 KO=0 ) 2024-04-22 02:24:32,806 INFO > userRestLogin (OK=15000 KO=0 ) 2024-04-22 02:24:32,806 INFO 2024-04-22 02:24:32,806 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,806 INFO [- ] 0% 2024-04-22 02:24:32,806 INFO waiting: 32509434 / active: 29 / done: 15000 2024-04-22 02:24:32,806 INFO ================================================================================ 2024-04-22 02:24:32,806 INFO 2024-04-22 02:24:32,806 INFO 2024-04-22 02:24:32,806 INFO ================================================================================ 2024-04-22 02:24:32,806 INFO 2024-04-22 02:22:16 15s elapsed 2024-04-22 02:24:32,806 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,806 INFO > Global (OK=33751 KO=0 ) 2024-04-22 02:24:32,806 INFO > userRestLogin (OK=33751 KO=0 ) 2024-04-22 02:24:32,806 INFO 2024-04-22 02:24:32,806 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,806 INFO [- ] 0% 2024-04-22 02:24:32,806 INFO waiting: 32490666 / active: 49 / done: 33748 2024-04-22 02:24:32,806 INFO ================================================================================ 2024-04-22 02:24:32,806 INFO 2024-04-22 02:24:32,806 INFO 2024-04-22 02:24:32,806 INFO ================================================================================ 2024-04-22 02:24:32,806 INFO 2024-04-22 02:22:21 20s elapsed 2024-04-22 02:24:32,806 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,806 INFO > Global (OK=59991 KO=0 ) 2024-04-22 02:24:32,806 INFO > userRestLogin (OK=59991 KO=0 ) 2024-04-22 02:24:32,806 INFO 2024-04-22 02:24:32,806 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,806 INFO [- ] 0% 2024-04-22 02:24:32,806 INFO waiting: 32464402 / active: 70 / done: 59991 2024-04-22 02:24:32,806 INFO ================================================================================ 2024-04-22 02:24:32,806 INFO 2024-04-22 02:24:32,806 INFO 2024-04-22 02:24:32,806 INFO ================================================================================ 2024-04-22 02:24:32,807 INFO 2024-04-22 02:22:26 25s elapsed 2024-04-22 02:24:32,807 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,807 INFO > Global (OK=93650 KO=0 ) 2024-04-22 02:24:32,807 INFO > userRestLogin (OK=93650 KO=0 ) 2024-04-22 02:24:32,807 INFO 2024-04-22 02:24:32,807 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,807 INFO [- ] 0% 2024-04-22 02:24:32,807 INFO waiting: 32430637 / active: 178 / done: 93648 2024-04-22 02:24:32,807 INFO ================================================================================ 2024-04-22 02:24:32,807 INFO 2024-04-22 02:24:32,807 INFO 2024-04-22 02:24:32,807 INFO ================================================================================ 2024-04-22 02:24:32,807 INFO 2024-04-22 02:22:31 30s elapsed 2024-04-22 02:24:32,807 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,807 INFO > Global (OK=134679 KO=0 ) 2024-04-22 02:24:32,807 INFO > userRestLogin (OK=134679 KO=0 ) 2024-04-22 02:24:32,807 INFO 2024-04-22 02:24:32,807 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,807 INFO [- ] 0% 2024-04-22 02:24:32,807 INFO waiting: 32389439 / active: 348 / done: 134676 2024-04-22 02:24:32,807 INFO ================================================================================ 2024-04-22 02:24:32,807 INFO 2024-04-22 02:24:32,807 INFO 2024-04-22 02:24:32,807 INFO ================================================================================ 2024-04-22 02:24:32,807 INFO 2024-04-22 02:22:36 35s elapsed 2024-04-22 02:24:32,807 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,807 INFO > Global (OK=179599 KO=0 ) 2024-04-22 02:24:32,807 INFO > userRestLogin (OK=179599 KO=0 ) 2024-04-22 02:24:32,807 INFO 2024-04-22 02:24:32,807 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,807 INFO [- ] 0% 2024-04-22 02:24:32,807 INFO waiting: 32344697 / active: 171 / done: 179595 2024-04-22 02:24:32,807 INFO ================================================================================ 2024-04-22 02:24:32,807 INFO 2024-04-22 02:24:32,807 INFO 2024-04-22 02:24:32,807 INFO ================================================================================ 2024-04-22 02:24:32,807 INFO 2024-04-22 02:22:41 40s elapsed 2024-04-22 02:24:32,807 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,807 INFO > Global (OK=224736 KO=0 ) 2024-04-22 02:24:32,807 INFO > userRestLogin (OK=224736 KO=0 ) 2024-04-22 02:24:32,807 INFO 2024-04-22 02:24:32,807 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,808 INFO [- ] 0% 2024-04-22 02:24:32,808 INFO waiting: 32299468 / active: 262 / done: 224733 2024-04-22 02:24:32,808 INFO ================================================================================ 2024-04-22 02:24:32,808 INFO 2024-04-22 02:24:32,808 INFO 2024-04-22 02:24:32,808 INFO [...] 2024-04-22 02:24:32,810 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,810 INFO > Global (OK=32298504 KO=0 ) 2024-04-22 02:24:32,810 INFO > userRestLogin (OK=32298504 KO=0 ) 2024-04-22 02:24:32,810 INFO 2024-04-22 02:24:32,810 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,810 INFO [#########################################################################-] 99% 2024-04-22 02:24:32,810 INFO waiting: 225481 / active: 481 / done: 32298501 2024-04-22 02:24:32,810 INFO ================================================================================ 2024-04-22 02:24:32,810 INFO 2024-04-22 02:24:32,810 INFO 2024-04-22 02:24:32,810 INFO ================================================================================ 2024-04-22 02:24:32,810 INFO 2024-04-22 03:22:11 3610s elapsed 2024-04-22 02:24:32,810 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,810 INFO > Global (OK=32343808 KO=0 ) 2024-04-22 02:24:32,810 INFO > userRestLogin (OK=32343808 KO=0 ) 2024-04-22 02:24:32,810 INFO 2024-04-22 02:24:32,810 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,810 INFO [#########################################################################-] 99% 2024-04-22 02:24:32,810 INFO waiting: 180249 / active: 408 / done: 32343806 2024-04-22 02:24:32,810 INFO ================================================================================ 2024-04-22 02:24:32,810 INFO 2024-04-22 02:24:32,811 INFO 2024-04-22 02:24:32,811 INFO ================================================================================ 2024-04-22 02:24:32,811 INFO 2024-04-22 03:22:16 3615s elapsed 2024-04-22 02:24:32,811 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,811 INFO > Global (OK=32388810 KO=0 ) 2024-04-22 02:24:32,811 INFO > userRestLogin (OK=32388810 KO=0 ) 2024-04-22 02:24:32,811 INFO 2024-04-22 02:24:32,811 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,811 INFO [#########################################################################-] 99% 2024-04-22 02:24:32,811 INFO waiting: 135301 / active: 354 / done: 32388808 2024-04-22 02:24:32,811 INFO ================================================================================ 2024-04-22 02:24:32,811 INFO 2024-04-22 02:24:32,811 INFO 2024-04-22 02:24:32,811 INFO ================================================================================ 2024-04-22 02:24:32,811 INFO 2024-04-22 03:22:21 3620s elapsed 2024-04-22 02:24:32,811 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,811 INFO > Global (OK=32433866 KO=0 ) 2024-04-22 02:24:32,811 INFO > userRestLogin (OK=32433866 KO=0 ) 2024-04-22 02:24:32,811 INFO 2024-04-22 02:24:32,811 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,811 INFO [#########################################################################-] 99% 2024-04-22 02:24:32,811 INFO waiting: 90224 / active: 373 / done: 32433866 2024-04-22 02:24:32,811 INFO ================================================================================ 2024-04-22 02:24:32,811 INFO 2024-04-22 02:24:32,811 INFO 2024-04-22 02:24:32,811 INFO ================================================================================ 2024-04-22 02:24:32,811 INFO 2024-04-22 03:22:26 3625s elapsed 2024-04-22 02:24:32,811 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,811 INFO > Global (OK=32478830 KO=0 ) 2024-04-22 02:24:32,811 INFO > userRestLogin (OK=32478830 KO=0 ) 2024-04-22 02:24:32,811 INFO 2024-04-22 02:24:32,811 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,811 INFO [#########################################################################-] 99% 2024-04-22 02:24:32,811 INFO waiting: 45223 / active: 412 / done: 32478828 2024-04-22 02:24:32,811 INFO ================================================================================ 2024-04-22 02:24:32,811 INFO 2024-04-22 02:24:32,811 INFO 2024-04-22 02:24:32,811 INFO ================================================================================ 2024-04-22 02:24:32,811 INFO 2024-04-22 03:22:31 3630s elapsed 2024-04-22 02:24:32,812 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,812 INFO > Global (OK=32523969 KO=0 ) 2024-04-22 02:24:32,812 INFO > userRestLogin (OK=32523969 KO=0 ) 2024-04-22 02:24:32,812 INFO 2024-04-22 02:24:32,812 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,812 INFO [#########################################################################-] 99% 2024-04-22 02:24:32,812 INFO waiting: 0 / active: 494 / done: 32523969 2024-04-22 02:24:32,812 INFO ================================================================================ 2024-04-22 02:24:32,812 INFO 2024-04-22 02:24:32,812 INFO 2024-04-22 02:24:32,812 INFO ================================================================================ 2024-04-22 02:24:32,812 INFO 2024-04-22 03:22:32 3630s elapsed 2024-04-22 02:24:32,812 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 02:24:32,812 INFO > Global (OK=32524463 KO=0 ) 2024-04-22 02:24:32,812 INFO > userRestLogin (OK=32524463 KO=0 ) 2024-04-22 02:24:32,812 INFO 2024-04-22 02:24:32,812 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 02:24:32,812 INFO [##########################################################################]100% 2024-04-22 02:24:32,812 INFO waiting: 0 / active: 0 / done: 32524463 2024-04-22 02:24:32,812 INFO ================================================================================ 2024-04-22 02:24:32,812 INFO 2024-04-22 02:24:32,812 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-22 02:24:32,812 INFO 2024-04-22 02:24:32,812 INFO ---------------------- 2024-04-22 02:24:32,812 INFO [22/Apr/2024 03:22:32] Create tar file authn-users-9000.tar.gz 2024-04-22 02:24:32,812 INFO ---------------------- 2024-04-22 02:24:32,812 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-22 02:24:32,812 INFO RESULT_FOLDER=authn-users-9000 2024-04-22 02:24:32,812 INFO SIMULATION_PATH=authn-users-9000/loadtest-20240422012200629 2024-04-22 02:24:32,812 INFO PWD=/results/gatling/authn-users-9000/loadtest-20240422012200629 2024-04-22 02:24:32,812 INFO [cmd] tar -czvf /results/gatling//authn-users-9000.tar.gz --exclude=simulation.log * 2024-04-22 02:24:32,812 INFO /results/gatling 2024-04-22 02:24:32,812 INFO 2024-04-22 02:24:32,812 INFO ---------------------- 2024-04-22 02:24:32,812 INFO [22/Apr/2024 03:22:32] Update /results/gatling//latest 2024-04-22 02:24:32,812 INFO ---------------------- 2024-04-22 02:24:32,812 INFO 2024-04-22 02:24:32,812 INFO ---------------------- 2024-04-22 02:24:32,812 INFO [22/Apr/2024 03:22:32] Update /results/gatling//latest.tar.gz 2024-04-22 02:24:32,812 INFO ---------------------- 2024-04-22 02:24:32,812 INFO ________________________________________________________________________________ [2024-04-22 02:24:32] authn-users-9000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-22 02:24:32,813 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-9000/stderr.txt is small (0 lines), printing all lines: 2024-04-22 02:24:32,813 DEBUG ----- output ----- 2024-04-22 02:24:32,813 DEBUG 2024-04-22 02:24:32,813 INFO ________________________________________________________________________________ [2024-04-22 02:24:32] authn-users-9000 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-22 02:24:32] authn-users-9000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-22 02:24:32] authn-users-9000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped