--Task-- name: authn-users-5500 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-5000'] preceding_task: authn-users-5000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 5500, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-14 21:46:48] authn-users-5500 pre : N/A ________________________________________________________________________________ [2024-04-14 21:46:48] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-14 21:46:48] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-5500/stdout.txt Pod log : /results/orders/authn-users-5500 2024-04-14 21:46:48,052 INFO 2024-04-14 21:46:48,053 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-14 21:46:48,053 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:48,380 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:48,380 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:48,380 DEBUG --- stdout --- 2024-04-14 21:46:48,380 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:48,380 DEBUG --- stderr --- 2024-04-14 21:46:48,380 DEBUG ________________________________________________________________________________ [2024-04-14 21:46:48] authn-users-5500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-14 21:46:48,381 INFO 2024-04-14 21:46:48,381 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-14 21:46:48,381 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:48,748 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:48,748 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:48,748 DEBUG --- stdout --- 2024-04-14 21:46:48,748 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:48,749 DEBUG --- stderr --- 2024-04-14 21:46:48,749 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-5500 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-14 21:46:48] authn-users-5500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-14 21:46:48,749 INFO 2024-04-14 21:46:48,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-14 21:46:48,749 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:49,105 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:49,105 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:49,105 DEBUG --- stdout --- 2024-04-14 21:46:49,105 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:49,105 DEBUG --- stderr --- 2024-04-14 21:46:49,105 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/order.json { "name": "authn-users-5500", "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-5500 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-5500", "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=5500 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-14 21:46:49] authn-users-5500 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-14 21:46:49,106 INFO 2024-04-14 21:46:49,106 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-14 21:46:49,106 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 21:46:49,360 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 21:46:49,360 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:49,360 DEBUG --- stdout --- 2024-04-14 21:46:49,360 DEBUG WAITING 2024-04-14 21:46:49,360 DEBUG --- stderr --- 2024-04-14 21:46:49,360 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 31 0 --:--:-- --:--:-- --:--:-- 31 ________________________________________________________________________________ [2024-04-14 21:46:49] authn-users-5500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-14 21:46:49,361 INFO 2024-04-14 21:46:49,361 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-14 21:46:49,361 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:49,679 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:49,680 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:49,680 DEBUG --- stdout --- 2024-04-14 21:46:49,680 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:49,680 DEBUG --- stderr --- 2024-04-14 21:46:49,680 DEBUG 2024-04-14 21:46:49,680 INFO 2024-04-14 21:46:49,680 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/order.json overseer-0-5584f6c4db-bcn5q:/results/orders/order.json 2024-04-14 21:46:49,680 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 21:46:51,090 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:51,090 DEBUG --- stdout --- 2024-04-14 21:46:51,090 DEBUG 2024-04-14 21:46:51,090 DEBUG --- stderr --- 2024-04-14 21:46:51,090 DEBUG Order has been successfully forwarded to overseer [2024-04-14 21:46:51] - INFO: Check if Gatling simulation started successfully 2024-04-14 21:46:51,091 INFO 2024-04-14 21:46:51,091 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-14 21:46:51,091 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:51,393 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:51,393 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:51,393 DEBUG --- stdout --- 2024-04-14 21:46:51,393 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:51,393 DEBUG --- stderr --- 2024-04-14 21:46:51,393 DEBUG 2024-04-14 21:46:51,394 INFO 2024-04-14 21:46:51,394 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- head -n 25 /results/orders/authn-users-5500/stdout.txt | grep "started..." 2024-04-14 21:46:51,394 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 21:46:52,154 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-14 21:46:57,933 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-14 21:47:03,692 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-14 21:47:03,692 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:47:03,693 DEBUG --- stdout --- 2024-04-14 21:47:03,693 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-14 21:47:03,693 DEBUG --- stderr --- 2024-04-14 21:47:03,693 DEBUG ________________________________________________________________________________ [2024-04-14 21:47:03] authn-users-5500 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 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-14 22:49:14] authn-users-5500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-14 22:49:14,219 INFO 2024-04-14 22:49:14,219 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-14 22:49:14,220 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:14,584 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:14,584 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:14,584 DEBUG --- stdout --- 2024-04-14 22:49:14,584 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:14,584 DEBUG --- stderr --- 2024-04-14 22:49:14,584 DEBUG 2024-04-14 22:49:14,584 INFO 2024-04-14 22:49:14,584 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- rm -f /results/orders/order.json 2024-04-14 22:49:14,585 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 22:49:15,460 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:15,462 DEBUG --- stdout --- 2024-04-14 22:49:15,462 DEBUG 2024-04-14 22:49:15,462 DEBUG --- stderr --- 2024-04-14 22:49:15,462 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-14 22:49:15] authn-users-5500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-14 22:49:15] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/order.json 2024-04-14 22:49:15,463 INFO 2024-04-14 22:49:15,463 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-14 22:49:15,463 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:15,790 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:15,790 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:15,790 DEBUG --- stdout --- 2024-04-14 22:49:15,790 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:15,790 DEBUG --- stderr --- 2024-04-14 22:49:15,790 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 22:49:15,790 INFO 2024-04-14 22:49:15,791 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-14 22:49:15,791 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:16,115 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:16,115 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:16,115 DEBUG --- stdout --- 2024-04-14 22:49:16,115 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:16,115 DEBUG --- stderr --- 2024-04-14 22:49:16,115 DEBUG 2024-04-14 22:49:16,116 INFO 2024-04-14 22:49:16,116 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-5500/rc.txt" ]; then echo "/results/orders/authn-users-5500/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-5500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5500/rc.txt file found" 2024-04-14 22:49:16,116 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 22:49:16,961 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 22:49:16,962 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:16,962 DEBUG --- stdout --- 2024-04-14 22:49:16,962 DEBUG /results/orders/authn-users-5500/rc.txt file found 2024-04-14 22:49:16,962 DEBUG --- stderr --- 2024-04-14 22:49:16,962 DEBUG 2024-04-14 22:49:16,962 INFO 2024-04-14 22:49:16,962 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-14 22:49:16,962 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:17,270 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:17,270 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:17,270 DEBUG --- stdout --- 2024-04-14 22:49:17,270 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:17,270 DEBUG --- stderr --- 2024-04-14 22:49:17,270 DEBUG 2024-04-14 22:49:17,271 INFO 2024-04-14 22:49:17,271 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-5500/rc.txt 2024-04-14 22:49:18,087 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 22:49:18,088 DEBUG --- stdout --- 2024-04-14 22:49:18,088 DEBUG 4 /results/orders/authn-users-5500/rc.txt 2024-04-14 22:49:18,088 DEBUG --- stderr --- 2024-04-14 22:49:18,088 DEBUG 2024-04-14 22:49:18,088 INFO - Download file (size 4k) 2024-04-14 22:49:18,088 INFO 2024-04-14 22:49:18,088 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-14 22:49:18,088 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:18,397 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:18,397 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:18,397 DEBUG --- stdout --- 2024-04-14 22:49:18,397 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:18,397 DEBUG --- stderr --- 2024-04-14 22:49:18,397 DEBUG source : /results/orders/authn-users-5500/rc.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/rc.txt 2024-04-14 22:49:18,397 INFO 2024-04-14 22:49:18,397 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 22:49:18,397 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:18,719 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:18,719 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:18,719 DEBUG --- stdout --- 2024-04-14 22:49:18,719 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:18,719 DEBUG --- stderr --- 2024-04-14 22:49:18,719 DEBUG 2024-04-14 22:49:18,720 INFO 2024-04-14 22:49:18,720 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-5500/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/rc.txt 2024-04-14 22:49:18,720 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 22:49:19,524 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:19,524 DEBUG --- stdout --- 2024-04-14 22:49:19,524 DEBUG tar: Removing leading `/' from member names 2024-04-14 22:49:19,524 DEBUG --- stderr --- 2024-04-14 22:49:19,524 DEBUG 2024-04-14 22:49:19,525 INFO 2024-04-14 22:49:19,525 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-14 22:49:19,525 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:19,844 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:19,845 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:19,845 DEBUG --- stdout --- 2024-04-14 22:49:19,845 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:19,845 DEBUG --- stderr --- 2024-04-14 22:49:19,845 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 22:49:19,845 INFO 2024-04-14 22:49:19,845 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-14 22:49:19,846 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:20,206 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:20,207 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:20,207 DEBUG --- stdout --- 2024-04-14 22:49:20,207 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:20,207 DEBUG --- stderr --- 2024-04-14 22:49:20,207 DEBUG 2024-04-14 22:49:20,207 INFO 2024-04-14 22:49:20,207 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-5500/stderr.txt" ]; then echo "/results/orders/authn-users-5500/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-5500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5500/stderr.txt file found" 2024-04-14 22:49:20,207 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 22:49:21,075 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 22:49:21,075 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:21,075 DEBUG --- stdout --- 2024-04-14 22:49:21,075 DEBUG /results/orders/authn-users-5500/stderr.txt file found 2024-04-14 22:49:21,075 DEBUG --- stderr --- 2024-04-14 22:49:21,075 DEBUG 2024-04-14 22:49:21,076 INFO 2024-04-14 22:49:21,076 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-14 22:49:21,076 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:21,397 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:21,398 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:21,398 DEBUG --- stdout --- 2024-04-14 22:49:21,398 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:21,398 DEBUG --- stderr --- 2024-04-14 22:49:21,398 DEBUG 2024-04-14 22:49:21,398 INFO 2024-04-14 22:49:21,398 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-5500/stderr.txt 2024-04-14 22:49:22,246 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 22:49:22,246 DEBUG --- stdout --- 2024-04-14 22:49:22,246 DEBUG 0 /results/orders/authn-users-5500/stderr.txt 2024-04-14 22:49:22,246 DEBUG --- stderr --- 2024-04-14 22:49:22,246 DEBUG 2024-04-14 22:49:22,246 INFO - Download file (size 0k) 2024-04-14 22:49:22,247 INFO 2024-04-14 22:49:22,247 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-14 22:49:22,247 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:22,571 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:22,571 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:22,571 DEBUG --- stdout --- 2024-04-14 22:49:22,571 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:22,571 DEBUG --- stderr --- 2024-04-14 22:49:22,571 DEBUG source : /results/orders/authn-users-5500/stderr.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/stderr.txt 2024-04-14 22:49:22,572 INFO 2024-04-14 22:49:22,572 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-14 22:49:22,572 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:22,895 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:22,896 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:22,896 DEBUG --- stdout --- 2024-04-14 22:49:22,896 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:22,896 DEBUG --- stderr --- 2024-04-14 22:49:22,896 DEBUG 2024-04-14 22:49:22,896 INFO 2024-04-14 22:49:22,896 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-5500/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/stderr.txt 2024-04-14 22:49:22,896 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 22:49:23,751 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:23,752 DEBUG --- stdout --- 2024-04-14 22:49:23,752 DEBUG tar: Removing leading `/' from member names 2024-04-14 22:49:23,752 DEBUG --- stderr --- 2024-04-14 22:49:23,752 DEBUG 2024-04-14 22:49:23,752 INFO 2024-04-14 22:49:23,752 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-14 22:49:23,752 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:24,077 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:24,078 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:24,078 DEBUG --- stdout --- 2024-04-14 22:49:24,078 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:24,078 DEBUG --- stderr --- 2024-04-14 22:49:24,078 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 22:49:24,078 INFO 2024-04-14 22:49:24,078 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-14 22:49:24,078 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:24,385 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:24,385 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:24,385 DEBUG --- stdout --- 2024-04-14 22:49:24,385 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:24,385 DEBUG --- stderr --- 2024-04-14 22:49:24,385 DEBUG 2024-04-14 22:49:24,386 INFO 2024-04-14 22:49:24,386 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-5500/stdout.txt" ]; then echo "/results/orders/authn-users-5500/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-5500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5500/stdout.txt file found" 2024-04-14 22:49:24,386 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 22:49:25,211 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 22:49:25,212 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:25,212 DEBUG --- stdout --- 2024-04-14 22:49:25,212 DEBUG /results/orders/authn-users-5500/stdout.txt file found 2024-04-14 22:49:25,212 DEBUG --- stderr --- 2024-04-14 22:49:25,212 DEBUG 2024-04-14 22:49:25,212 INFO 2024-04-14 22:49:25,212 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-14 22:49:25,212 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:25,532 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:25,532 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:25,532 DEBUG --- stdout --- 2024-04-14 22:49:25,532 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:25,532 DEBUG --- stderr --- 2024-04-14 22:49:25,532 DEBUG 2024-04-14 22:49:25,532 INFO 2024-04-14 22:49:25,532 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-5500/stdout.txt 2024-04-14 22:49:26,374 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 22:49:26,375 DEBUG --- stdout --- 2024-04-14 22:49:26,375 DEBUG 508 /results/orders/authn-users-5500/stdout.txt 2024-04-14 22:49:26,376 DEBUG --- stderr --- 2024-04-14 22:49:26,376 DEBUG 2024-04-14 22:49:26,376 INFO - Download file (size 508k) 2024-04-14 22:49:26,376 INFO 2024-04-14 22:49:26,376 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-14 22:49:26,376 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:26,705 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:26,705 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:26,705 DEBUG --- stdout --- 2024-04-14 22:49:26,706 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:26,706 DEBUG --- stderr --- 2024-04-14 22:49:26,706 DEBUG source : /results/orders/authn-users-5500/stdout.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/stdout.txt 2024-04-14 22:49:26,706 INFO 2024-04-14 22:49:26,706 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-14 22:49:26,706 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:27,030 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:27,030 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:27,030 DEBUG --- stdout --- 2024-04-14 22:49:27,030 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:27,030 DEBUG --- stderr --- 2024-04-14 22:49:27,030 DEBUG 2024-04-14 22:49:27,031 INFO 2024-04-14 22:49:27,031 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-5500/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/stdout.txt 2024-04-14 22:49:27,031 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 22:49:28,122 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:28,122 DEBUG --- stdout --- 2024-04-14 22:49:28,122 DEBUG tar: Removing leading `/' from member names 2024-04-14 22:49:28,123 DEBUG --- stderr --- 2024-04-14 22:49:28,123 DEBUG ________________________________________________________________________________ [2024-04-14 22:49:28] authn-users-5500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-14 22:49:28,123 INFO 2024-04-14 22:49:28,123 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-14 22:49:28,123 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:28,449 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:28,449 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:28,449 DEBUG --- stdout --- 2024-04-14 22:49:28,449 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:28,449 DEBUG --- stderr --- 2024-04-14 22:49:28,449 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 22:49:28,449 INFO 2024-04-14 22:49:28,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-14 22:49:28,450 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:28,810 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:28,810 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:28,810 DEBUG --- stdout --- 2024-04-14 22:49:28,810 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:28,810 DEBUG --- stderr --- 2024-04-14 22:49:28,810 DEBUG 2024-04-14 22:49:28,811 INFO 2024-04-14 22:49:28,811 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-5500/cmd.txt" ]; then echo "/results/orders/authn-users-5500/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-5500/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5500/cmd.txt file found" 2024-04-14 22:49:28,811 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 22:49:29,664 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 22:49:29,664 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:29,664 DEBUG --- stdout --- 2024-04-14 22:49:29,664 DEBUG /results/orders/authn-users-5500/cmd.txt file found 2024-04-14 22:49:29,665 DEBUG --- stderr --- 2024-04-14 22:49:29,665 DEBUG 2024-04-14 22:49:29,665 INFO 2024-04-14 22:49:29,665 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-14 22:49:29,665 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:29,987 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:29,987 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:29,988 DEBUG --- stdout --- 2024-04-14 22:49:29,988 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:29,988 DEBUG --- stderr --- 2024-04-14 22:49:29,988 DEBUG 2024-04-14 22:49:29,988 INFO 2024-04-14 22:49:29,988 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-5500/cmd.txt 2024-04-14 22:49:30,827 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 22:49:30,827 DEBUG --- stdout --- 2024-04-14 22:49:30,827 DEBUG 4 /results/orders/authn-users-5500/cmd.txt 2024-04-14 22:49:30,827 DEBUG --- stderr --- 2024-04-14 22:49:30,827 DEBUG 2024-04-14 22:49:30,827 INFO - Download file (size 4k) 2024-04-14 22:49:30,828 INFO 2024-04-14 22:49:30,828 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-14 22:49:30,828 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:31,191 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:31,191 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:31,191 DEBUG --- stdout --- 2024-04-14 22:49:31,191 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:31,191 DEBUG --- stderr --- 2024-04-14 22:49:31,191 DEBUG source : /results/orders/authn-users-5500/cmd.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/cmd.txt 2024-04-14 22:49:31,192 INFO 2024-04-14 22:49:31,192 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-14 22:49:31,192 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:31,501 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:31,502 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:31,502 DEBUG --- stdout --- 2024-04-14 22:49:31,502 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:31,502 DEBUG --- stderr --- 2024-04-14 22:49:31,502 DEBUG 2024-04-14 22:49:31,503 INFO 2024-04-14 22:49:31,503 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-5500/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/cmd.txt 2024-04-14 22:49:31,503 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 22:49:32,282 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:32,283 DEBUG --- stdout --- 2024-04-14 22:49:32,283 DEBUG tar: Removing leading `/' from member names 2024-04-14 22:49:32,283 DEBUG --- stderr --- 2024-04-14 22:49:32,283 DEBUG 2024-04-14 22:49:32,283 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/cmd.txt is small (1 lines), printing all lines: 2024-04-14 22:49:32,283 DEBUG ----- output ----- 2024-04-14 22:49:32,283 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-5500 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-14 22:49:32,283 INFO ________________________________________________________________________________ [2024-04-14 22:49:32] authn-users-5500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-14 22:49:32,284 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/rc.txt is small (1 lines), printing all lines: 2024-04-14 22:49:32,284 DEBUG ----- output ----- 2024-04-14 22:49:32,284 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-14 22:49:32,284 INFO ________________________________________________________________________________ [2024-04-14 22:49:32] authn-users-5500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-14 22:49:32,285 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/stdout.txt is 8746 lines): 2024-04-14 22:49:32,285 DEBUG ----- output ----- 2024-04-14 22:49:32,285 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-14 22:49:32,285 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-14 22:49:32,285 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-14 22:49:32,285 INFO 2024-04-14 22:49:32,285 INFO ================================================================================ 2024-04-14 22:49:32,285 INFO 2024-04-14 22:47:03 5s elapsed 2024-04-14 22:49:32,285 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,285 INFO > Global (OK=2290 KO=0 ) 2024-04-14 22:49:32,285 INFO > userRestLogin (OK=2290 KO=0 ) 2024-04-14 22:49:32,285 INFO 2024-04-14 22:49:32,285 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,286 INFO [- ] 0% 2024-04-14 22:49:32,286 INFO waiting: 19879189 / active: 10 / done: 2289 2024-04-14 22:49:32,286 INFO ================================================================================ 2024-04-14 22:49:32,286 INFO 2024-04-14 22:49:32,286 INFO 2024-04-14 22:49:32,286 INFO ================================================================================ 2024-04-14 22:49:32,286 INFO 2024-04-14 22:47:08 10s elapsed 2024-04-14 22:49:32,286 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,286 INFO > Global (OK=9160 KO=0 ) 2024-04-14 22:49:32,286 INFO > userRestLogin (OK=9160 KO=0 ) 2024-04-14 22:49:32,286 INFO 2024-04-14 22:49:32,286 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,286 INFO [- ] 0% 2024-04-14 22:49:32,286 INFO waiting: 19872308 / active: 20 / done: 9160 2024-04-14 22:49:32,286 INFO ================================================================================ 2024-04-14 22:49:32,286 INFO 2024-04-14 22:49:32,286 INFO 2024-04-14 22:49:32,286 INFO ================================================================================ 2024-04-14 22:49:32,286 INFO 2024-04-14 22:47:13 15s elapsed 2024-04-14 22:49:32,286 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,286 INFO > Global (OK=20617 KO=0 ) 2024-04-14 22:49:32,286 INFO > userRestLogin (OK=20617 KO=0 ) 2024-04-14 22:49:32,286 INFO 2024-04-14 22:49:32,286 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,286 INFO [- ] 0% 2024-04-14 22:49:32,286 INFO waiting: 19860843 / active: 28 / done: 20617 2024-04-14 22:49:32,286 INFO ================================================================================ 2024-04-14 22:49:32,286 INFO 2024-04-14 22:49:32,286 INFO 2024-04-14 22:49:32,286 INFO ================================================================================ 2024-04-14 22:49:32,286 INFO 2024-04-14 22:47:18 20s elapsed 2024-04-14 22:49:32,286 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,286 INFO > Global (OK=36639 KO=0 ) 2024-04-14 22:49:32,286 INFO > userRestLogin (OK=36639 KO=0 ) 2024-04-14 22:49:32,286 INFO 2024-04-14 22:49:32,286 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,286 INFO [- ] 0% 2024-04-14 22:49:32,286 INFO waiting: 19844795 / active: 56 / done: 36637 2024-04-14 22:49:32,286 INFO ================================================================================ 2024-04-14 22:49:32,287 INFO 2024-04-14 22:49:32,287 INFO 2024-04-14 22:49:32,287 INFO ================================================================================ 2024-04-14 22:49:32,287 INFO 2024-04-14 22:47:23 25s elapsed 2024-04-14 22:49:32,287 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,287 INFO > Global (OK=57256 KO=0 ) 2024-04-14 22:49:32,287 INFO > userRestLogin (OK=57256 KO=0 ) 2024-04-14 22:49:32,287 INFO 2024-04-14 22:49:32,287 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,287 INFO [- ] 0% 2024-04-14 22:49:32,287 INFO waiting: 19824164 / active: 68 / done: 57256 2024-04-14 22:49:32,287 INFO ================================================================================ 2024-04-14 22:49:32,287 INFO 2024-04-14 22:49:32,287 INFO 2024-04-14 22:49:32,287 INFO ================================================================================ 2024-04-14 22:49:32,287 INFO 2024-04-14 22:47:28 30s elapsed 2024-04-14 22:49:32,287 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,287 INFO > Global (OK=82319 KO=0 ) 2024-04-14 22:49:32,287 INFO > userRestLogin (OK=82319 KO=0 ) 2024-04-14 22:49:32,287 INFO 2024-04-14 22:49:32,287 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,287 INFO [- ] 0% 2024-04-14 22:49:32,287 INFO waiting: 19799069 / active: 100 / done: 82319 2024-04-14 22:49:32,287 INFO ================================================================================ 2024-04-14 22:49:32,287 INFO 2024-04-14 22:49:32,287 INFO 2024-04-14 22:49:32,287 INFO ================================================================================ 2024-04-14 22:49:32,287 INFO 2024-04-14 22:47:33 35s elapsed 2024-04-14 22:49:32,287 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,287 INFO > Global (OK=109931 KO=0 ) 2024-04-14 22:49:32,287 INFO > userRestLogin (OK=109931 KO=0 ) 2024-04-14 22:49:32,287 INFO 2024-04-14 22:49:32,287 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,287 INFO [- ] 0% 2024-04-14 22:49:32,287 INFO waiting: 19771315 / active: 244 / done: 109929 2024-04-14 22:49:32,287 INFO ================================================================================ 2024-04-14 22:49:32,287 INFO 2024-04-14 22:49:32,287 INFO 2024-04-14 22:49:32,287 INFO ================================================================================ 2024-04-14 22:49:32,287 INFO 2024-04-14 22:47:38 40s elapsed 2024-04-14 22:49:32,287 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,288 INFO > Global (OK=137323 KO=0 ) 2024-04-14 22:49:32,288 INFO > userRestLogin (OK=137323 KO=0 ) 2024-04-14 22:49:32,288 INFO 2024-04-14 22:49:32,288 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,288 INFO [- ] 0% 2024-04-14 22:49:32,288 INFO waiting: 19743971 / active: 194 / done: 137323 2024-04-14 22:49:32,288 INFO ================================================================================ 2024-04-14 22:49:32,288 INFO 2024-04-14 22:49:32,288 INFO 2024-04-14 22:49:32,288 INFO [...] 2024-04-14 22:49:32,290 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,290 INFO > Global (OK=19743708 KO=0 ) 2024-04-14 22:49:32,290 INFO > userRestLogin (OK=19743708 KO=0 ) 2024-04-14 22:49:32,290 INFO 2024-04-14 22:49:32,290 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,290 INFO [#########################################################################-] 99% 2024-04-14 22:49:32,290 INFO waiting: 137495 / active: 285 / done: 19743708 2024-04-14 22:49:32,290 INFO ================================================================================ 2024-04-14 22:49:32,290 INFO 2024-04-14 22:49:32,290 INFO 2024-04-14 22:49:32,290 INFO ================================================================================ 2024-04-14 22:49:32,290 INFO 2024-04-14 23:47:08 3610s elapsed 2024-04-14 22:49:32,290 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,290 INFO > Global (OK=19771375 KO=0 ) 2024-04-14 22:49:32,291 INFO > userRestLogin (OK=19771375 KO=0 ) 2024-04-14 22:49:32,291 INFO 2024-04-14 22:49:32,291 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,291 INFO [#########################################################################-] 99% 2024-04-14 22:49:32,291 INFO waiting: 110031 / active: 85 / done: 19771372 2024-04-14 22:49:32,291 INFO ================================================================================ 2024-04-14 22:49:32,291 INFO 2024-04-14 22:49:32,291 INFO 2024-04-14 22:49:32,291 INFO ================================================================================ 2024-04-14 22:49:32,291 INFO 2024-04-14 23:47:13 3615s elapsed 2024-04-14 22:49:32,291 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,291 INFO > Global (OK=19798986 KO=0 ) 2024-04-14 22:49:32,291 INFO > userRestLogin (OK=19798986 KO=0 ) 2024-04-14 22:49:32,291 INFO 2024-04-14 22:49:32,291 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,291 INFO [#########################################################################-] 99% 2024-04-14 22:49:32,291 INFO waiting: 82399 / active: 103 / done: 19798986 2024-04-14 22:49:32,291 INFO ================================================================================ 2024-04-14 22:49:32,291 INFO 2024-04-14 22:49:32,291 INFO 2024-04-14 22:49:32,291 INFO ================================================================================ 2024-04-14 22:49:32,291 INFO 2024-04-14 23:47:18 3620s elapsed 2024-04-14 22:49:32,291 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,291 INFO > Global (OK=19826824 KO=0 ) 2024-04-14 22:49:32,291 INFO > userRestLogin (OK=19826824 KO=0 ) 2024-04-14 22:49:32,291 INFO 2024-04-14 22:49:32,291 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,291 INFO [#########################################################################-] 99% 2024-04-14 22:49:32,291 INFO waiting: 54553 / active: 112 / done: 19826823 2024-04-14 22:49:32,291 INFO ================================================================================ 2024-04-14 22:49:32,291 INFO 2024-04-14 22:49:32,291 INFO 2024-04-14 22:49:32,291 INFO ================================================================================ 2024-04-14 22:49:32,291 INFO 2024-04-14 23:47:23 3625s elapsed 2024-04-14 22:49:32,291 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,291 INFO > Global (OK=19853877 KO=0 ) 2024-04-14 22:49:32,291 INFO > userRestLogin (OK=19853877 KO=0 ) 2024-04-14 22:49:32,291 INFO 2024-04-14 22:49:32,291 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,291 INFO [#########################################################################-] 99% 2024-04-14 22:49:32,292 INFO waiting: 27496 / active: 118 / done: 19853874 2024-04-14 22:49:32,292 INFO ================================================================================ 2024-04-14 22:49:32,292 INFO 2024-04-14 22:49:32,292 INFO 2024-04-14 22:49:32,292 INFO ================================================================================ 2024-04-14 22:49:32,292 INFO 2024-04-14 23:47:28 3630s elapsed 2024-04-14 22:49:32,292 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,292 INFO > Global (OK=19881422 KO=0 ) 2024-04-14 22:49:32,292 INFO > userRestLogin (OK=19881422 KO=0 ) 2024-04-14 22:49:32,292 INFO 2024-04-14 22:49:32,292 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,292 INFO [#########################################################################-] 99% 2024-04-14 22:49:32,292 INFO waiting: 0 / active: 67 / done: 19881421 2024-04-14 22:49:32,292 INFO ================================================================================ 2024-04-14 22:49:32,292 INFO 2024-04-14 22:49:32,292 INFO 2024-04-14 22:49:32,292 INFO ================================================================================ 2024-04-14 22:49:32,292 INFO 2024-04-14 23:47:28 3630s elapsed 2024-04-14 22:49:32,292 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 22:49:32,292 INFO > Global (OK=19881488 KO=0 ) 2024-04-14 22:49:32,292 INFO > userRestLogin (OK=19881488 KO=0 ) 2024-04-14 22:49:32,292 INFO 2024-04-14 22:49:32,292 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 22:49:32,292 INFO [##########################################################################]100% 2024-04-14 22:49:32,292 INFO waiting: 0 / active: 0 / done: 19881488 2024-04-14 22:49:32,292 INFO ================================================================================ 2024-04-14 22:49:32,292 INFO 2024-04-14 22:49:32,292 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-14 22:49:32,292 INFO 2024-04-14 22:49:32,292 INFO ---------------------- 2024-04-14 22:49:32,292 INFO [14/Apr/2024 23:47:28] Create tar file authn-users-5500.tar.gz 2024-04-14 22:49:32,292 INFO ---------------------- 2024-04-14 22:49:32,292 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-14 22:49:32,292 INFO RESULT_FOLDER=authn-users-5500 2024-04-14 22:49:32,292 INFO SIMULATION_PATH=authn-users-5500/loadtest-20240414214658166 2024-04-14 22:49:32,292 INFO PWD=/results/gatling/authn-users-5500/loadtest-20240414214658166 2024-04-14 22:49:32,292 INFO [cmd] tar -czvf /results/gatling//authn-users-5500.tar.gz --exclude=simulation.log * 2024-04-14 22:49:32,292 INFO /results/gatling 2024-04-14 22:49:32,292 INFO 2024-04-14 22:49:32,292 INFO ---------------------- 2024-04-14 22:49:32,292 INFO [14/Apr/2024 23:47:28] Update /results/gatling//latest 2024-04-14 22:49:32,292 INFO ---------------------- 2024-04-14 22:49:32,293 INFO 2024-04-14 22:49:32,293 INFO ---------------------- 2024-04-14 22:49:32,293 INFO [14/Apr/2024 23:47:28] Update /results/gatling//latest.tar.gz 2024-04-14 22:49:32,293 INFO ---------------------- 2024-04-14 22:49:32,293 INFO ________________________________________________________________________________ [2024-04-14 22:49:32] authn-users-5500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-14 22:49:32,293 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-5500/stderr.txt is small (0 lines), printing all lines: 2024-04-14 22:49:32,293 DEBUG ----- output ----- 2024-04-14 22:49:32,293 DEBUG 2024-04-14 22:49:32,293 INFO ________________________________________________________________________________ [2024-04-14 22:49:32] authn-users-5500 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-14 22:49:32] authn-users-5500 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-14 22:49:32] authn-users-5500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped