--Task-- name: authn-users-6000 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-3000'] preceding_task: authn-users-3000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 6000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-22 00:19:04] authn-users-6000 pre : N/A ________________________________________________________________________________ [2024-04-22 00:19:04] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-22 00:19:04] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-6000/stdout.txt Pod log : /results/orders/authn-users-6000 2024-04-22 00:19:04,171 INFO 2024-04-22 00:19:04,172 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 00:19:04,172 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:19:04,538 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:19:04,538 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:04,539 DEBUG --- stdout --- 2024-04-22 00:19:04,539 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:19:04,539 DEBUG --- stderr --- 2024-04-22 00:19:04,539 DEBUG ________________________________________________________________________________ [2024-04-22 00:19:04] authn-users-6000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-22 00:19:04,539 INFO 2024-04-22 00:19:04,539 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 00:19:04,539 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:19:04,910 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:19:04,910 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:04,910 DEBUG --- stdout --- 2024-04-22 00:19:04,910 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:19:04,910 DEBUG --- stderr --- 2024-04-22 00:19:04,910 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-6000 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-22 00:19:04] authn-users-6000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-22 00:19:04,911 INFO 2024-04-22 00:19:04,911 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 00:19:04,911 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:19:05,243 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:19:05,243 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:05,243 DEBUG --- stdout --- 2024-04-22 00:19:05,243 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:19:05,243 DEBUG --- stderr --- 2024-04-22 00:19:05,243 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/order.json { "name": "authn-users-6000", "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-6000 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/authn-users-6000", "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=6000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-22 00:19:05] authn-users-6000 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 00:19:05,245 INFO 2024-04-22 00:19:05,245 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-22 00:19:05,245 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-22 00:19:05,476 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 00:19:05,476 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:05,476 DEBUG --- stdout --- 2024-04-22 00:19:05,476 DEBUG WAITING 2024-04-22 00:19:05,476 DEBUG --- stderr --- 2024-04-22 00:19:05,476 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 00:19:05] authn-users-6000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-22 00:19:05,477 INFO 2024-04-22 00:19:05,477 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 00:19:05,477 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:19:05,785 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:19:05,786 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:05,786 DEBUG --- stdout --- 2024-04-22 00:19:05,786 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:19:05,786 DEBUG --- stderr --- 2024-04-22 00:19:05,786 DEBUG 2024-04-22 00:19:05,786 INFO 2024-04-22 00:19:05,786 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-6000/order.json overseer-0-68c5d5b884-p97w5:/results/orders/order.json 2024-04-22 00:19:05,786 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 00:19:07,207 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:07,207 DEBUG --- stdout --- 2024-04-22 00:19:07,207 DEBUG 2024-04-22 00:19:07,207 DEBUG --- stderr --- 2024-04-22 00:19:07,207 DEBUG Order has been successfully forwarded to overseer [2024-04-22 00:19:07] - INFO: Check if Gatling simulation started successfully 2024-04-22 00:19:07,207 INFO 2024-04-22 00:19:07,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 00:19:07,208 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:19:07,539 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:19:07,539 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:07,539 DEBUG --- stdout --- 2024-04-22 00:19:07,539 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:19:07,539 DEBUG --- stderr --- 2024-04-22 00:19:07,539 DEBUG 2024-04-22 00:19:07,539 INFO 2024-04-22 00:19:07,540 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-6000/stdout.txt | grep "started..." 2024-04-22 00:19:07,540 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-22 00:19:08,297 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-22 00:19:14,098 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-22 00:19:19,858 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-22 00:19:19,858 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:19,858 DEBUG --- stdout --- 2024-04-22 00:19:19,858 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 00:19:19,858 DEBUG --- stderr --- 2024-04-22 00:19:19,858 DEBUG ________________________________________________________________________________ [2024-04-22 00:19:19] authn-users-6000 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 07s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 09s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 10s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-22 01:21:30] authn-users-6000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-22 01:21:30,642 INFO 2024-04-22 01:21:30,642 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:30,645 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:31,053 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:31,053 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:31,053 DEBUG --- stdout --- 2024-04-22 01:21:31,054 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:31,054 DEBUG --- stderr --- 2024-04-22 01:21:31,054 DEBUG 2024-04-22 01:21:31,054 INFO 2024-04-22 01:21:31,054 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 01:21:31,054 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 01:21:31,923 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:31,923 DEBUG --- stdout --- 2024-04-22 01:21:31,923 DEBUG 2024-04-22 01:21:31,923 DEBUG --- stderr --- 2024-04-22 01:21:31,923 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-22 01:21:31] authn-users-6000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-22 01:21:31] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/order.json 2024-04-22 01:21:31,924 INFO 2024-04-22 01:21:31,925 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:31,925 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:32,287 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:32,287 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:32,287 DEBUG --- stdout --- 2024-04-22 01:21:32,287 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:32,287 DEBUG --- stderr --- 2024-04-22 01:21:32,287 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 01:21:32,287 INFO 2024-04-22 01:21:32,287 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:32,287 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:32,639 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:32,639 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:32,640 DEBUG --- stdout --- 2024-04-22 01:21:32,640 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:32,640 DEBUG --- stderr --- 2024-04-22 01:21:32,640 DEBUG 2024-04-22 01:21:32,641 INFO 2024-04-22 01:21:32,641 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-6000/rc.txt" ]; then echo "/results/orders/authn-users-6000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/rc.txt file found" 2024-04-22 01:21:32,641 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 01:21:33,510 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 01:21:33,510 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:33,511 DEBUG --- stdout --- 2024-04-22 01:21:33,511 DEBUG /results/orders/authn-users-6000/rc.txt file found 2024-04-22 01:21:33,511 DEBUG --- stderr --- 2024-04-22 01:21:33,511 DEBUG 2024-04-22 01:21:33,511 INFO 2024-04-22 01:21:33,511 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:33,511 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:33,825 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:33,826 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:33,826 DEBUG --- stdout --- 2024-04-22 01:21:33,826 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:33,826 DEBUG --- stderr --- 2024-04-22 01:21:33,826 DEBUG 2024-04-22 01:21:33,826 INFO 2024-04-22 01:21:33,827 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-6000/rc.txt 2024-04-22 01:21:34,655 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 01:21:34,655 DEBUG --- stdout --- 2024-04-22 01:21:34,655 DEBUG 4 /results/orders/authn-users-6000/rc.txt 2024-04-22 01:21:34,655 DEBUG --- stderr --- 2024-04-22 01:21:34,656 DEBUG 2024-04-22 01:21:34,656 INFO - Download file (size 4k) 2024-04-22 01:21:34,656 INFO 2024-04-22 01:21:34,656 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:34,656 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:34,988 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:34,989 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:34,989 DEBUG --- stdout --- 2024-04-22 01:21:34,989 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:34,989 DEBUG --- stderr --- 2024-04-22 01:21:34,989 DEBUG source : /results/orders/authn-users-6000/rc.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/rc.txt 2024-04-22 01:21:34,989 INFO 2024-04-22 01:21:34,989 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:34,989 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:35,314 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:35,314 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:35,315 DEBUG --- stdout --- 2024-04-22 01:21:35,315 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:35,315 DEBUG --- stderr --- 2024-04-22 01:21:35,315 DEBUG 2024-04-22 01:21:35,315 INFO 2024-04-22 01:21:35,315 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-6000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/rc.txt 2024-04-22 01:21:35,315 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 01:21:36,109 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:36,109 DEBUG --- stdout --- 2024-04-22 01:21:36,109 DEBUG tar: Removing leading `/' from member names 2024-04-22 01:21:36,109 DEBUG --- stderr --- 2024-04-22 01:21:36,109 DEBUG 2024-04-22 01:21:36,110 INFO 2024-04-22 01:21:36,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-22 01:21:36,110 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:36,422 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:36,422 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:36,422 DEBUG --- stdout --- 2024-04-22 01:21:36,422 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:36,422 DEBUG --- stderr --- 2024-04-22 01:21:36,422 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 01:21:36,423 INFO 2024-04-22 01:21:36,423 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:36,423 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:36,749 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:36,750 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:36,750 DEBUG --- stdout --- 2024-04-22 01:21:36,750 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:36,750 DEBUG --- stderr --- 2024-04-22 01:21:36,750 DEBUG 2024-04-22 01:21:36,751 INFO 2024-04-22 01:21:36,751 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-6000/stderr.txt" ]; then echo "/results/orders/authn-users-6000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/stderr.txt file found" 2024-04-22 01:21:36,751 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 01:21:37,607 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 01:21:37,608 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:37,608 DEBUG --- stdout --- 2024-04-22 01:21:37,608 DEBUG /results/orders/authn-users-6000/stderr.txt file found 2024-04-22 01:21:37,608 DEBUG --- stderr --- 2024-04-22 01:21:37,608 DEBUG 2024-04-22 01:21:37,609 INFO 2024-04-22 01:21:37,609 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:37,609 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:37,937 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:37,940 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:37,940 DEBUG --- stdout --- 2024-04-22 01:21:37,940 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:37,940 DEBUG --- stderr --- 2024-04-22 01:21:37,940 DEBUG 2024-04-22 01:21:37,940 INFO 2024-04-22 01:21:37,941 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-6000/stderr.txt 2024-04-22 01:21:38,807 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 01:21:38,807 DEBUG --- stdout --- 2024-04-22 01:21:38,807 DEBUG 0 /results/orders/authn-users-6000/stderr.txt 2024-04-22 01:21:38,807 DEBUG --- stderr --- 2024-04-22 01:21:38,807 DEBUG 2024-04-22 01:21:38,807 INFO - Download file (size 0k) 2024-04-22 01:21:38,808 INFO 2024-04-22 01:21:38,808 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:38,808 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:39,117 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:39,118 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:39,118 DEBUG --- stdout --- 2024-04-22 01:21:39,118 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:39,118 DEBUG --- stderr --- 2024-04-22 01:21:39,118 DEBUG source : /results/orders/authn-users-6000/stderr.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stderr.txt 2024-04-22 01:21:39,118 INFO 2024-04-22 01:21:39,118 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:39,118 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:39,425 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:39,425 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:39,425 DEBUG --- stdout --- 2024-04-22 01:21:39,425 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:39,425 DEBUG --- stderr --- 2024-04-22 01:21:39,425 DEBUG 2024-04-22 01:21:39,426 INFO 2024-04-22 01:21:39,426 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-6000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stderr.txt 2024-04-22 01:21:39,426 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 01:21:40,169 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:40,170 DEBUG --- stdout --- 2024-04-22 01:21:40,170 DEBUG tar: Removing leading `/' from member names 2024-04-22 01:21:40,170 DEBUG --- stderr --- 2024-04-22 01:21:40,170 DEBUG 2024-04-22 01:21:40,170 INFO 2024-04-22 01:21:40,170 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:40,170 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:40,475 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:40,475 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:40,475 DEBUG --- stdout --- 2024-04-22 01:21:40,475 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:40,475 DEBUG --- stderr --- 2024-04-22 01:21:40,475 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 01:21:40,476 INFO 2024-04-22 01:21:40,476 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:40,476 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:40,779 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:40,779 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:40,779 DEBUG --- stdout --- 2024-04-22 01:21:40,779 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:40,779 DEBUG --- stderr --- 2024-04-22 01:21:40,779 DEBUG 2024-04-22 01:21:40,780 INFO 2024-04-22 01:21:40,780 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-6000/stdout.txt" ]; then echo "/results/orders/authn-users-6000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/stdout.txt file found" 2024-04-22 01:21:40,780 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 01:21:41,618 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 01:21:41,618 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:41,618 DEBUG --- stdout --- 2024-04-22 01:21:41,618 DEBUG /results/orders/authn-users-6000/stdout.txt file found 2024-04-22 01:21:41,618 DEBUG --- stderr --- 2024-04-22 01:21:41,618 DEBUG 2024-04-22 01:21:41,619 INFO 2024-04-22 01:21:41,619 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:41,619 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:41,930 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:41,930 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:41,931 DEBUG --- stdout --- 2024-04-22 01:21:41,931 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:41,931 DEBUG --- stderr --- 2024-04-22 01:21:41,931 DEBUG 2024-04-22 01:21:41,931 INFO 2024-04-22 01:21:41,931 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-6000/stdout.txt 2024-04-22 01:21:42,744 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 01:21:42,745 DEBUG --- stdout --- 2024-04-22 01:21:42,745 DEBUG 508 /results/orders/authn-users-6000/stdout.txt 2024-04-22 01:21:42,745 DEBUG --- stderr --- 2024-04-22 01:21:42,745 DEBUG 2024-04-22 01:21:42,745 INFO - Download file (size 508k) 2024-04-22 01:21:42,745 INFO 2024-04-22 01:21:42,745 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:42,745 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:43,057 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:43,059 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:43,059 DEBUG --- stdout --- 2024-04-22 01:21:43,059 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:43,059 DEBUG --- stderr --- 2024-04-22 01:21:43,059 DEBUG source : /results/orders/authn-users-6000/stdout.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stdout.txt 2024-04-22 01:21:43,060 INFO 2024-04-22 01:21:43,060 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:43,060 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:43,371 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:43,372 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:43,372 DEBUG --- stdout --- 2024-04-22 01:21:43,372 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:43,372 DEBUG --- stderr --- 2024-04-22 01:21:43,372 DEBUG 2024-04-22 01:21:43,372 INFO 2024-04-22 01:21:43,372 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-6000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stdout.txt 2024-04-22 01:21:43,372 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 01:21:44,533 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:44,533 DEBUG --- stdout --- 2024-04-22 01:21:44,533 DEBUG tar: Removing leading `/' from member names 2024-04-22 01:21:44,533 DEBUG --- stderr --- 2024-04-22 01:21:44,533 DEBUG ________________________________________________________________________________ [2024-04-22 01:21:44] authn-users-6000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-22 01:21:44,534 INFO 2024-04-22 01:21:44,534 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:44,534 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:44,870 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:44,870 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:44,870 DEBUG --- stdout --- 2024-04-22 01:21:44,870 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:44,870 DEBUG --- stderr --- 2024-04-22 01:21:44,870 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 01:21:44,870 INFO 2024-04-22 01:21:44,870 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:44,870 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:45,183 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:45,183 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:45,183 DEBUG --- stdout --- 2024-04-22 01:21:45,184 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:45,184 DEBUG --- stderr --- 2024-04-22 01:21:45,184 DEBUG 2024-04-22 01:21:45,184 INFO 2024-04-22 01:21:45,184 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-6000/cmd.txt" ]; then echo "/results/orders/authn-users-6000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/cmd.txt file found" 2024-04-22 01:21:45,184 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 01:21:46,013 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 01:21:46,013 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:46,013 DEBUG --- stdout --- 2024-04-22 01:21:46,013 DEBUG /results/orders/authn-users-6000/cmd.txt file found 2024-04-22 01:21:46,013 DEBUG --- stderr --- 2024-04-22 01:21:46,013 DEBUG 2024-04-22 01:21:46,014 INFO 2024-04-22 01:21:46,014 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:46,014 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:46,318 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:46,318 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:46,318 DEBUG --- stdout --- 2024-04-22 01:21:46,318 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:46,318 DEBUG --- stderr --- 2024-04-22 01:21:46,318 DEBUG 2024-04-22 01:21:46,318 INFO 2024-04-22 01:21:46,319 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-6000/cmd.txt 2024-04-22 01:21:47,140 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 01:21:47,140 DEBUG --- stdout --- 2024-04-22 01:21:47,140 DEBUG 4 /results/orders/authn-users-6000/cmd.txt 2024-04-22 01:21:47,140 DEBUG --- stderr --- 2024-04-22 01:21:47,140 DEBUG 2024-04-22 01:21:47,140 INFO - Download file (size 4k) 2024-04-22 01:21:47,141 INFO 2024-04-22 01:21:47,141 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:47,141 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:47,448 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:47,448 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:47,448 DEBUG --- stdout --- 2024-04-22 01:21:47,448 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:47,448 DEBUG --- stderr --- 2024-04-22 01:21:47,448 DEBUG source : /results/orders/authn-users-6000/cmd.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/cmd.txt 2024-04-22 01:21:47,449 INFO 2024-04-22 01:21:47,449 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:47,449 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 01:21:47,753 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 01:21:47,753 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:47,753 DEBUG --- stdout --- 2024-04-22 01:21:47,754 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 01:21:47,754 DEBUG --- stderr --- 2024-04-22 01:21:47,754 DEBUG 2024-04-22 01:21:47,754 INFO 2024-04-22 01:21:47,754 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-6000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/cmd.txt 2024-04-22 01:21:47,754 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 01:21:48,508 INFO [loop_until]: OK (rc = 0) 2024-04-22 01:21:48,509 DEBUG --- stdout --- 2024-04-22 01:21:48,509 DEBUG tar: Removing leading `/' from member names 2024-04-22 01:21:48,509 DEBUG --- stderr --- 2024-04-22 01:21:48,509 DEBUG 2024-04-22 01:21:48,509 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/cmd.txt is small (1 lines), printing all lines: 2024-04-22 01:21:48,509 DEBUG ----- output ----- 2024-04-22 01:21:48,509 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-6000 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-22 01:21:48,509 INFO ________________________________________________________________________________ [2024-04-22 01:21:48] authn-users-6000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-22 01:21:48,510 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/rc.txt is small (1 lines), printing all lines: 2024-04-22 01:21:48,510 DEBUG ----- output ----- 2024-04-22 01:21:48,510 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-22 01:21:48,510 INFO ________________________________________________________________________________ [2024-04-22 01:21:48] authn-users-6000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-22 01:21:48,512 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-6000/stdout.txt is 8734 lines): 2024-04-22 01:21:48,512 DEBUG ----- output ----- 2024-04-22 01:21:48,512 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-22 01:21:48,512 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-22 01:21:48,512 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-22 01:21:48,512 INFO 2024-04-22 01:21:48,512 INFO ================================================================================ 2024-04-22 01:21:48,512 INFO 2024-04-22 01:19:20 5s elapsed 2024-04-22 01:21:48,512 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,512 INFO > Global (OK=2505 KO=0 ) 2024-04-22 01:21:48,512 INFO > userRestLogin (OK=2505 KO=0 ) 2024-04-22 01:21:48,512 INFO 2024-04-22 01:21:48,512 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,512 INFO [- ] 0% 2024-04-22 01:21:48,512 INFO waiting: 21685910 / active: 10 / done: 2505 2024-04-22 01:21:48,512 INFO ================================================================================ 2024-04-22 01:21:48,512 INFO 2024-04-22 01:21:48,512 INFO 2024-04-22 01:21:48,512 INFO ================================================================================ 2024-04-22 01:21:48,512 INFO 2024-04-22 01:19:25 10s elapsed 2024-04-22 01:21:48,512 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,512 INFO > Global (OK=10009 KO=0 ) 2024-04-22 01:21:48,512 INFO > userRestLogin (OK=10009 KO=0 ) 2024-04-22 01:21:48,512 INFO 2024-04-22 01:21:48,512 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,512 INFO [- ] 0% 2024-04-22 01:21:48,512 INFO waiting: 21678397 / active: 19 / done: 10009 2024-04-22 01:21:48,512 INFO ================================================================================ 2024-04-22 01:21:48,512 INFO 2024-04-22 01:21:48,512 INFO 2024-04-22 01:21:48,512 INFO ================================================================================ 2024-04-22 01:21:48,512 INFO 2024-04-22 01:19:30 15s elapsed 2024-04-22 01:21:48,512 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,512 INFO > Global (OK=22509 KO=0 ) 2024-04-22 01:21:48,512 INFO > userRestLogin (OK=22509 KO=0 ) 2024-04-22 01:21:48,513 INFO 2024-04-22 01:21:48,513 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,513 INFO [- ] 0% 2024-04-22 01:21:48,513 INFO waiting: 21665884 / active: 32 / done: 22509 2024-04-22 01:21:48,513 INFO ================================================================================ 2024-04-22 01:21:48,513 INFO 2024-04-22 01:21:48,513 INFO 2024-04-22 01:21:48,513 INFO ================================================================================ 2024-04-22 01:21:48,513 INFO 2024-04-22 01:19:35 20s elapsed 2024-04-22 01:21:48,513 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,513 INFO > Global (OK=40007 KO=0 ) 2024-04-22 01:21:48,513 INFO > userRestLogin (OK=40007 KO=0 ) 2024-04-22 01:21:48,513 INFO 2024-04-22 01:21:48,513 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,513 INFO [- ] 0% 2024-04-22 01:21:48,513 INFO waiting: 21648375 / active: 44 / done: 40006 2024-04-22 01:21:48,513 INFO ================================================================================ 2024-04-22 01:21:48,513 INFO 2024-04-22 01:21:48,513 INFO 2024-04-22 01:21:48,513 INFO ================================================================================ 2024-04-22 01:21:48,513 INFO 2024-04-22 01:19:40 25s elapsed 2024-04-22 01:21:48,513 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,513 INFO > Global (OK=62504 KO=0 ) 2024-04-22 01:21:48,513 INFO > userRestLogin (OK=62504 KO=0 ) 2024-04-22 01:21:48,513 INFO 2024-04-22 01:21:48,513 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,513 INFO [- ] 0% 2024-04-22 01:21:48,513 INFO waiting: 21625868 / active: 53 / done: 62504 2024-04-22 01:21:48,513 INFO ================================================================================ 2024-04-22 01:21:48,513 INFO 2024-04-22 01:21:48,513 INFO 2024-04-22 01:21:48,513 INFO ================================================================================ 2024-04-22 01:21:48,513 INFO 2024-04-22 01:19:45 30s elapsed 2024-04-22 01:21:48,513 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,513 INFO > Global (OK=89888 KO=0 ) 2024-04-22 01:21:48,513 INFO > userRestLogin (OK=89888 KO=0 ) 2024-04-22 01:21:48,513 INFO 2024-04-22 01:21:48,513 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,513 INFO [- ] 0% 2024-04-22 01:21:48,513 INFO waiting: 21598354 / active: 183 / done: 89888 2024-04-22 01:21:48,513 INFO ================================================================================ 2024-04-22 01:21:48,513 INFO 2024-04-22 01:21:48,514 INFO 2024-04-22 01:21:48,514 INFO ================================================================================ 2024-04-22 01:21:48,514 INFO 2024-04-22 01:19:50 35s elapsed 2024-04-22 01:21:48,514 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,514 INFO > Global (OK=120048 KO=0 ) 2024-04-22 01:21:48,514 INFO > userRestLogin (OK=120048 KO=0 ) 2024-04-22 01:21:48,514 INFO 2024-04-22 01:21:48,514 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,514 INFO [- ] 0% 2024-04-22 01:21:48,514 INFO waiting: 21568293 / active: 87 / done: 120045 2024-04-22 01:21:48,514 INFO ================================================================================ 2024-04-22 01:21:48,514 INFO 2024-04-22 01:21:48,514 INFO 2024-04-22 01:21:48,514 INFO ================================================================================ 2024-04-22 01:21:48,514 INFO 2024-04-22 01:19:55 40s elapsed 2024-04-22 01:21:48,514 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,514 INFO > Global (OK=149978 KO=0 ) 2024-04-22 01:21:48,514 INFO > userRestLogin (OK=149978 KO=0 ) 2024-04-22 01:21:48,514 INFO 2024-04-22 01:21:48,514 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,514 INFO [- ] 0% 2024-04-22 01:21:48,514 INFO waiting: 21538375 / active: 72 / done: 149978 2024-04-22 01:21:48,514 INFO ================================================================================ 2024-04-22 01:21:48,514 INFO 2024-04-22 01:21:48,514 INFO 2024-04-22 01:21:48,514 INFO [...] 2024-04-22 01:21:48,517 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,517 INFO > Global (OK=21508453 KO=0 ) 2024-04-22 01:21:48,517 INFO > userRestLogin (OK=21508453 KO=0 ) 2024-04-22 01:21:48,517 INFO 2024-04-22 01:21:48,517 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,517 INFO [#########################################################################-] 99% 2024-04-22 01:21:48,517 INFO waiting: 179897 / active: 76 / done: 21508452 2024-04-22 01:21:48,517 INFO ================================================================================ 2024-04-22 01:21:48,517 INFO 2024-04-22 01:21:48,517 INFO 2024-04-22 01:21:48,517 INFO ================================================================================ 2024-04-22 01:21:48,517 INFO 2024-04-22 02:19:20 3605s elapsed 2024-04-22 01:21:48,517 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,517 INFO > Global (OK=21538450 KO=0 ) 2024-04-22 01:21:48,517 INFO > userRestLogin (OK=21538450 KO=0 ) 2024-04-22 01:21:48,517 INFO 2024-04-22 01:21:48,517 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,517 INFO [#########################################################################-] 99% 2024-04-22 01:21:48,517 INFO waiting: 149889 / active: 88 / done: 21538448 2024-04-22 01:21:48,517 INFO ================================================================================ 2024-04-22 01:21:48,517 INFO 2024-04-22 01:21:48,517 INFO 2024-04-22 01:21:48,517 INFO ================================================================================ 2024-04-22 01:21:48,517 INFO 2024-04-22 02:19:25 3610s elapsed 2024-04-22 01:21:48,517 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,517 INFO > Global (OK=21568358 KO=0 ) 2024-04-22 01:21:48,517 INFO > userRestLogin (OK=21568358 KO=0 ) 2024-04-22 01:21:48,517 INFO 2024-04-22 01:21:48,517 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,517 INFO [#########################################################################-] 99% 2024-04-22 01:21:48,518 INFO waiting: 119992 / active: 75 / done: 21568358 2024-04-22 01:21:48,518 INFO ================================================================================ 2024-04-22 01:21:48,518 INFO 2024-04-22 01:21:48,518 INFO 2024-04-22 01:21:48,518 INFO ================================================================================ 2024-04-22 01:21:48,518 INFO 2024-04-22 02:19:30 3615s elapsed 2024-04-22 01:21:48,518 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,518 INFO > Global (OK=21598380 KO=0 ) 2024-04-22 01:21:48,518 INFO > userRestLogin (OK=21598380 KO=0 ) 2024-04-22 01:21:48,518 INFO 2024-04-22 01:21:48,518 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,518 INFO [#########################################################################-] 99% 2024-04-22 01:21:48,518 INFO waiting: 89989 / active: 58 / done: 21598378 2024-04-22 01:21:48,518 INFO ================================================================================ 2024-04-22 01:21:48,518 INFO 2024-04-22 01:21:48,518 INFO 2024-04-22 01:21:48,518 INFO ================================================================================ 2024-04-22 01:21:48,518 INFO 2024-04-22 02:19:35 3620s elapsed 2024-04-22 01:21:48,518 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,518 INFO > Global (OK=21628505 KO=0 ) 2024-04-22 01:21:48,518 INFO > userRestLogin (OK=21628505 KO=0 ) 2024-04-22 01:21:48,518 INFO 2024-04-22 01:21:48,518 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,518 INFO [#########################################################################-] 99% 2024-04-22 01:21:48,518 INFO waiting: 59856 / active: 65 / done: 21628504 2024-04-22 01:21:48,518 INFO ================================================================================ 2024-04-22 01:21:48,518 INFO 2024-04-22 01:21:48,518 INFO 2024-04-22 01:21:48,518 INFO ================================================================================ 2024-04-22 01:21:48,518 INFO 2024-04-22 02:19:40 3625s elapsed 2024-04-22 01:21:48,518 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,518 INFO > Global (OK=21658355 KO=0 ) 2024-04-22 01:21:48,518 INFO > userRestLogin (OK=21658355 KO=0 ) 2024-04-22 01:21:48,518 INFO 2024-04-22 01:21:48,518 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,518 INFO [#########################################################################-] 99% 2024-04-22 01:21:48,518 INFO waiting: 30019 / active: 53 / done: 21658353 2024-04-22 01:21:48,518 INFO ================================================================================ 2024-04-22 01:21:48,519 INFO 2024-04-22 01:21:48,519 INFO 2024-04-22 01:21:48,519 INFO ================================================================================ 2024-04-22 01:21:48,519 INFO 2024-04-22 02:19:45 3630s elapsed 2024-04-22 01:21:48,519 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 01:21:48,519 INFO > Global (OK=21688425 KO=0 ) 2024-04-22 01:21:48,519 INFO > userRestLogin (OK=21688425 KO=0 ) 2024-04-22 01:21:48,519 INFO 2024-04-22 01:21:48,519 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 01:21:48,519 INFO [##########################################################################]100% 2024-04-22 01:21:48,519 INFO waiting: 0 / active: 0 / done: 21688425 2024-04-22 01:21:48,519 INFO ================================================================================ 2024-04-22 01:21:48,519 INFO 2024-04-22 01:21:48,519 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-22 01:21:48,519 INFO 2024-04-22 01:21:48,519 INFO ---------------------- 2024-04-22 01:21:48,519 INFO [22/Apr/2024 02:19:45] Create tar file authn-users-6000.tar.gz 2024-04-22 01:21:48,519 INFO ---------------------- 2024-04-22 01:21:48,519 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-22 01:21:48,519 INFO RESULT_FOLDER=authn-users-6000 2024-04-22 01:21:48,519 INFO SIMULATION_PATH=authn-users-6000/loadtest-20240422001914400 2024-04-22 01:21:48,519 INFO PWD=/results/gatling/authn-users-6000/loadtest-20240422001914400 2024-04-22 01:21:48,519 INFO [cmd] tar -czvf /results/gatling//authn-users-6000.tar.gz --exclude=simulation.log * 2024-04-22 01:21:48,519 INFO /results/gatling 2024-04-22 01:21:48,519 INFO 2024-04-22 01:21:48,519 INFO ---------------------- 2024-04-22 01:21:48,519 INFO [22/Apr/2024 02:19:45] Update /results/gatling//latest 2024-04-22 01:21:48,519 INFO ---------------------- 2024-04-22 01:21:48,519 INFO 2024-04-22 01:21:48,519 INFO ---------------------- 2024-04-22 01:21:48,519 INFO [22/Apr/2024 02:19:45] Update /results/gatling//latest.tar.gz 2024-04-22 01:21:48,519 INFO ---------------------- 2024-04-22 01:21:48,519 INFO ________________________________________________________________________________ [2024-04-22 01:21:48] authn-users-6000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-22 01:21:48,520 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stderr.txt is small (0 lines), printing all lines: 2024-04-22 01:21:48,520 DEBUG ----- output ----- 2024-04-22 01:21:48,520 DEBUG 2024-04-22 01:21:48,520 INFO ________________________________________________________________________________ [2024-04-22 01:21:48] authn-users-6000 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 01:21:48] authn-users-6000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-22 01:21:48] authn-users-6000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped