--Task-- name: authn-users-500 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-100'] preceding_task: authn-users-100 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 500, 'rampup-duration': 30, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-09 23:31:46] authn-users-500 pre : N/A ________________________________________________________________________________ [2024-04-09 23:31:46] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-09 23:31:46] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-500/stdout.txt Pod log : /results/orders/authn-users-500 2024-04-09 23:31:46,606 INFO 2024-04-09 23:31:46,606 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-09 23:31:46,607 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:46,955 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:46,955 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:46,955 DEBUG --- stdout --- 2024-04-09 23:31:46,955 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:46,955 DEBUG --- stderr --- 2024-04-09 23:31:46,956 DEBUG ________________________________________________________________________________ [2024-04-09 23:31:46] authn-users-500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-09 23:31:46,956 INFO 2024-04-09 23:31:46,956 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-09 23:31:46,956 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:47,294 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:47,295 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:47,295 DEBUG --- stdout --- 2024-04-09 23:31:47,295 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:47,295 DEBUG --- stderr --- 2024-04-09 23:31:47,295 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-500 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-09 23:31:47] authn-users-500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-09 23:31:47,296 INFO 2024-04-09 23:31:47,296 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-09 23:31:47,296 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:47,631 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:47,631 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:47,631 DEBUG --- stdout --- 2024-04-09 23:31:47,631 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:47,631 DEBUG --- stderr --- 2024-04-09 23:31:47,631 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/order.json { "name": "authn-users-500", "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-500 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-500", "environment": { "JAVA_OPTS": "-Dam_host=xlou.iam.xlou-cdm.engineeringpit.com -DamadminPassword=**** -DamadminUsername=amadmin -Dduration=3600 -Dnum_users=1000000 -Drampup_duration=30 -DtestCase=am.AMAuthN -DtestMode=open -Dthroughput=500 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-09 23:31:47] authn-users-500 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-09 23:31:47,633 INFO 2024-04-09 23:31:47,633 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-09 23:31:47,633 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-09 23:31:47,877 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-09 23:31:47,877 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:47,877 DEBUG --- stdout --- 2024-04-09 23:31:47,877 DEBUG WAITING 2024-04-09 23:31:47,877 DEBUG --- stderr --- 2024-04-09 23:31:47,877 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-09 23:31:47] authn-users-500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-09 23:31:47,878 INFO 2024-04-09 23:31:47,878 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-09 23:31:47,878 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:48,209 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:48,209 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:48,209 DEBUG --- stdout --- 2024-04-09 23:31:48,209 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:48,209 DEBUG --- stderr --- 2024-04-09 23:31:48,209 DEBUG 2024-04-09 23:31:48,210 INFO 2024-04-09 23:31:48,210 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/02_authn-users-500/order.json overseer-0-6f8784665d-zq5hb:/results/orders/order.json 2024-04-09 23:31:48,210 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-09 23:31:49,668 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:49,668 DEBUG --- stdout --- 2024-04-09 23:31:49,669 DEBUG 2024-04-09 23:31:49,669 DEBUG --- stderr --- 2024-04-09 23:31:49,669 DEBUG Order has been successfully forwarded to overseer [2024-04-09 23:31:49] - INFO: Check if Gatling simulation started successfully 2024-04-09 23:31:49,670 INFO 2024-04-09 23:31:49,670 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-09 23:31:49,670 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:50,096 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:50,098 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:50,098 DEBUG --- stdout --- 2024-04-09 23:31:50,098 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:50,098 DEBUG --- stderr --- 2024-04-09 23:31:50,098 DEBUG 2024-04-09 23:31:50,099 INFO 2024-04-09 23:31:50,099 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb -- head -n 25 /results/orders/authn-users-500/stdout.txt | grep "started..." 2024-04-09 23:31:50,099 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-09 23:31:50,928 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-09 23:31:56,744 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-09 23:32:02,514 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-09 23:32:08,276 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-09 23:32:08,276 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:32:08,276 DEBUG --- stdout --- 2024-04-09 23:32:08,276 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 00:31:53.288 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 00:31:59.941 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 00:31:59.944 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 00:32:02.519 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-04-09 23:32:08,276 DEBUG --- stderr --- 2024-04-09 23:32:08,276 DEBUG ________________________________________________________________________________ [2024-04-09 23:32:08] authn-users-500 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 08s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 09s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 10s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-10 00:34:18] authn-users-500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-10 00:34:18,987 INFO 2024-04-10 00:34:18,987 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:18,987 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:19,339 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:19,339 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:19,339 DEBUG --- stdout --- 2024-04-10 00:34:19,340 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:19,340 DEBUG --- stderr --- 2024-04-10 00:34:19,340 DEBUG 2024-04-10 00:34:19,340 INFO 2024-04-10 00:34:19,340 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb -- rm -f /results/orders/order.json 2024-04-10 00:34:19,340 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 00:34:20,189 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:20,190 DEBUG --- stdout --- 2024-04-10 00:34:20,190 DEBUG 2024-04-10 00:34:20,190 DEBUG --- stderr --- 2024-04-10 00:34:20,190 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-10 00:34:20] authn-users-500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-10 00:34:20] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/order.json 2024-04-10 00:34:20,190 INFO 2024-04-10 00:34:20,190 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:20,191 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:20,521 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:20,521 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:20,521 DEBUG --- stdout --- 2024-04-10 00:34:20,522 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:20,522 DEBUG --- stderr --- 2024-04-10 00:34:20,522 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 00:34:20,522 INFO 2024-04-10 00:34:20,522 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:20,522 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:20,857 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:20,857 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:20,857 DEBUG --- stdout --- 2024-04-10 00:34:20,857 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:20,857 DEBUG --- stderr --- 2024-04-10 00:34:20,857 DEBUG 2024-04-10 00:34:20,858 INFO 2024-04-10 00:34:20,858 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-500/rc.txt" ]; then echo "/results/orders/authn-users-500/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-500/rc.txt file found" 2024-04-10 00:34:20,858 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 00:34:21,705 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 00:34:21,705 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:21,705 DEBUG --- stdout --- 2024-04-10 00:34:21,705 DEBUG /results/orders/authn-users-500/rc.txt file found 2024-04-10 00:34:21,705 DEBUG --- stderr --- 2024-04-10 00:34:21,705 DEBUG 2024-04-10 00:34:21,706 INFO 2024-04-10 00:34:21,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-10 00:34:21,706 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:22,038 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:22,038 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:22,038 DEBUG --- stdout --- 2024-04-10 00:34:22,038 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:22,038 DEBUG --- stderr --- 2024-04-10 00:34:22,038 DEBUG 2024-04-10 00:34:22,038 INFO 2024-04-10 00:34:22,039 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-500/rc.txt 2024-04-10 00:34:22,889 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 00:34:22,889 DEBUG --- stdout --- 2024-04-10 00:34:22,890 DEBUG 4 /results/orders/authn-users-500/rc.txt 2024-04-10 00:34:22,890 DEBUG --- stderr --- 2024-04-10 00:34:22,890 DEBUG 2024-04-10 00:34:22,890 INFO - Download file (size 4k) 2024-04-10 00:34:22,890 INFO 2024-04-10 00:34:22,890 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:22,890 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:23,227 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:23,228 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:23,228 DEBUG --- stdout --- 2024-04-10 00:34:23,228 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:23,228 DEBUG --- stderr --- 2024-04-10 00:34:23,228 DEBUG source : /results/orders/authn-users-500/rc.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/rc.txt 2024-04-10 00:34:23,228 INFO 2024-04-10 00:34:23,228 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:23,228 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:23,608 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:23,608 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:23,608 DEBUG --- stdout --- 2024-04-10 00:34:23,608 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:23,608 DEBUG --- stderr --- 2024-04-10 00:34:23,608 DEBUG 2024-04-10 00:34:23,609 INFO 2024-04-10 00:34:23,609 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-500/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/rc.txt 2024-04-10 00:34:23,609 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 00:34:24,403 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:24,404 DEBUG --- stdout --- 2024-04-10 00:34:24,404 DEBUG tar: Removing leading `/' from member names 2024-04-10 00:34:24,404 DEBUG --- stderr --- 2024-04-10 00:34:24,404 DEBUG 2024-04-10 00:34:24,405 INFO 2024-04-10 00:34:24,405 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:24,406 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:24,790 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:24,790 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:24,790 DEBUG --- stdout --- 2024-04-10 00:34:24,790 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:24,790 DEBUG --- stderr --- 2024-04-10 00:34:24,790 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 00:34:24,791 INFO 2024-04-10 00:34:24,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-10 00:34:24,791 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:25,172 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:25,172 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:25,172 DEBUG --- stdout --- 2024-04-10 00:34:25,172 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:25,172 DEBUG --- stderr --- 2024-04-10 00:34:25,172 DEBUG 2024-04-10 00:34:25,173 INFO 2024-04-10 00:34:25,173 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-500/stderr.txt" ]; then echo "/results/orders/authn-users-500/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-500/stderr.txt file found" 2024-04-10 00:34:25,173 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 00:34:26,046 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 00:34:26,047 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:26,047 DEBUG --- stdout --- 2024-04-10 00:34:26,047 DEBUG /results/orders/authn-users-500/stderr.txt file found 2024-04-10 00:34:26,047 DEBUG --- stderr --- 2024-04-10 00:34:26,047 DEBUG 2024-04-10 00:34:26,047 INFO 2024-04-10 00:34:26,047 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:26,047 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:26,375 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:26,375 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:26,375 DEBUG --- stdout --- 2024-04-10 00:34:26,375 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:26,375 DEBUG --- stderr --- 2024-04-10 00:34:26,375 DEBUG 2024-04-10 00:34:26,375 INFO 2024-04-10 00:34:26,375 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-500/stderr.txt 2024-04-10 00:34:27,232 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 00:34:27,232 DEBUG --- stdout --- 2024-04-10 00:34:27,232 DEBUG 0 /results/orders/authn-users-500/stderr.txt 2024-04-10 00:34:27,232 DEBUG --- stderr --- 2024-04-10 00:34:27,232 DEBUG 2024-04-10 00:34:27,232 INFO - Download file (size 0k) 2024-04-10 00:34:27,233 INFO 2024-04-10 00:34:27,233 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:27,233 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:27,554 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:27,555 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:27,555 DEBUG --- stdout --- 2024-04-10 00:34:27,555 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:27,555 DEBUG --- stderr --- 2024-04-10 00:34:27,555 DEBUG source : /results/orders/authn-users-500/stderr.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/stderr.txt 2024-04-10 00:34:27,555 INFO 2024-04-10 00:34:27,555 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:27,555 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:27,881 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:27,881 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:27,881 DEBUG --- stdout --- 2024-04-10 00:34:27,881 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:27,881 DEBUG --- stderr --- 2024-04-10 00:34:27,881 DEBUG 2024-04-10 00:34:27,882 INFO 2024-04-10 00:34:27,882 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-500/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/stderr.txt 2024-04-10 00:34:27,882 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 00:34:28,645 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:28,646 DEBUG --- stdout --- 2024-04-10 00:34:28,646 DEBUG tar: Removing leading `/' from member names 2024-04-10 00:34:28,646 DEBUG --- stderr --- 2024-04-10 00:34:28,646 DEBUG 2024-04-10 00:34:28,646 INFO 2024-04-10 00:34:28,646 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:28,646 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:28,973 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:28,973 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:28,973 DEBUG --- stdout --- 2024-04-10 00:34:28,973 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:28,974 DEBUG --- stderr --- 2024-04-10 00:34:28,974 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 00:34:28,974 INFO 2024-04-10 00:34:28,974 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:28,974 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:29,316 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:29,316 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:29,316 DEBUG --- stdout --- 2024-04-10 00:34:29,316 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:29,316 DEBUG --- stderr --- 2024-04-10 00:34:29,316 DEBUG 2024-04-10 00:34:29,317 INFO 2024-04-10 00:34:29,317 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-500/stdout.txt" ]; then echo "/results/orders/authn-users-500/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-500/stdout.txt file found" 2024-04-10 00:34:29,317 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 00:34:30,212 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 00:34:30,212 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:30,212 DEBUG --- stdout --- 2024-04-10 00:34:30,212 DEBUG /results/orders/authn-users-500/stdout.txt file found 2024-04-10 00:34:30,212 DEBUG --- stderr --- 2024-04-10 00:34:30,212 DEBUG 2024-04-10 00:34:30,213 INFO 2024-04-10 00:34:30,213 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:30,213 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:30,539 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:30,539 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:30,539 DEBUG --- stdout --- 2024-04-10 00:34:30,539 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:30,539 DEBUG --- stderr --- 2024-04-10 00:34:30,539 DEBUG 2024-04-10 00:34:30,539 INFO 2024-04-10 00:34:30,540 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-500/stdout.txt 2024-04-10 00:34:31,393 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 00:34:31,394 DEBUG --- stdout --- 2024-04-10 00:34:31,394 DEBUG 504 /results/orders/authn-users-500/stdout.txt 2024-04-10 00:34:31,394 DEBUG --- stderr --- 2024-04-10 00:34:31,394 DEBUG 2024-04-10 00:34:31,394 INFO - Download file (size 504k) 2024-04-10 00:34:31,394 INFO 2024-04-10 00:34:31,395 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:31,395 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:31,722 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:31,722 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:31,722 DEBUG --- stdout --- 2024-04-10 00:34:31,722 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:31,722 DEBUG --- stderr --- 2024-04-10 00:34:31,722 DEBUG source : /results/orders/authn-users-500/stdout.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/stdout.txt 2024-04-10 00:34:31,723 INFO 2024-04-10 00:34:31,723 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:31,723 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:32,047 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:32,048 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:32,048 DEBUG --- stdout --- 2024-04-10 00:34:32,048 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:32,048 DEBUG --- stderr --- 2024-04-10 00:34:32,048 DEBUG 2024-04-10 00:34:32,048 INFO 2024-04-10 00:34:32,048 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-500/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/stdout.txt 2024-04-10 00:34:32,048 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 00:34:33,129 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:33,130 DEBUG --- stdout --- 2024-04-10 00:34:33,130 DEBUG tar: Removing leading `/' from member names 2024-04-10 00:34:33,130 DEBUG --- stderr --- 2024-04-10 00:34:33,130 DEBUG ________________________________________________________________________________ [2024-04-10 00:34:33] authn-users-500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-10 00:34:33,131 INFO 2024-04-10 00:34:33,131 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:33,131 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:33,455 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:33,455 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:33,455 DEBUG --- stdout --- 2024-04-10 00:34:33,455 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:33,455 DEBUG --- stderr --- 2024-04-10 00:34:33,455 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-10 00:34:33,456 INFO 2024-04-10 00:34:33,456 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:33,456 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:33,797 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:33,798 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:33,798 DEBUG --- stdout --- 2024-04-10 00:34:33,798 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:33,798 DEBUG --- stderr --- 2024-04-10 00:34:33,798 DEBUG 2024-04-10 00:34:33,799 INFO 2024-04-10 00:34:33,799 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- bash -c 'if [ -f "/results/orders/authn-users-500/cmd.txt" ]; then echo "/results/orders/authn-users-500/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-500/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-500/cmd.txt file found" 2024-04-10 00:34:33,799 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-10 00:34:34,640 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-10 00:34:34,640 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:34,640 DEBUG --- stdout --- 2024-04-10 00:34:34,640 DEBUG /results/orders/authn-users-500/cmd.txt file found 2024-04-10 00:34:34,640 DEBUG --- stderr --- 2024-04-10 00:34:34,640 DEBUG 2024-04-10 00:34:34,641 INFO 2024-04-10 00:34:34,641 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:34,641 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:34,954 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:34,954 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:34,954 DEBUG --- stdout --- 2024-04-10 00:34:34,954 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:34,954 DEBUG --- stderr --- 2024-04-10 00:34:34,954 DEBUG 2024-04-10 00:34:34,955 INFO 2024-04-10 00:34:34,955 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6f8784665d-zq5hb --stdin -- du -k /results/orders/authn-users-500/cmd.txt 2024-04-10 00:34:35,814 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-10 00:34:35,815 DEBUG --- stdout --- 2024-04-10 00:34:35,815 DEBUG 4 /results/orders/authn-users-500/cmd.txt 2024-04-10 00:34:35,815 DEBUG --- stderr --- 2024-04-10 00:34:35,815 DEBUG 2024-04-10 00:34:35,815 INFO - Download file (size 4k) 2024-04-10 00:34:35,815 INFO 2024-04-10 00:34:35,815 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:35,815 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:36,131 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:36,132 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:36,132 DEBUG --- stdout --- 2024-04-10 00:34:36,132 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:36,132 DEBUG --- stderr --- 2024-04-10 00:34:36,132 DEBUG source : /results/orders/authn-users-500/cmd.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/cmd.txt 2024-04-10 00:34:36,132 INFO 2024-04-10 00:34:36,132 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-10 00:34:36,132 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-10 00:34:36,441 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-10 00:34:36,441 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:36,441 DEBUG --- stdout --- 2024-04-10 00:34:36,441 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-10 00:34:36,441 DEBUG --- stderr --- 2024-04-10 00:34:36,441 DEBUG 2024-04-10 00:34:36,442 INFO 2024-04-10 00:34:36,442 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6f8784665d-zq5hb:/results/orders/authn-users-500/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/cmd.txt 2024-04-10 00:34:36,442 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-10 00:34:37,191 INFO [loop_until]: OK (rc = 0) 2024-04-10 00:34:37,191 DEBUG --- stdout --- 2024-04-10 00:34:37,191 DEBUG tar: Removing leading `/' from member names 2024-04-10 00:34:37,191 DEBUG --- stderr --- 2024-04-10 00:34:37,191 DEBUG 2024-04-10 00:34:37,191 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/cmd.txt is small (1 lines), printing all lines: 2024-04-10 00:34:37,191 DEBUG ----- output ----- 2024-04-10 00:34:37,191 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-500 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-10 00:34:37,192 INFO ________________________________________________________________________________ [2024-04-10 00:34:37] authn-users-500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-10 00:34:37,192 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/rc.txt is small (1 lines), printing all lines: 2024-04-10 00:34:37,192 DEBUG ----- output ----- 2024-04-10 00:34:37,192 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-10 00:34:37,192 INFO ________________________________________________________________________________ [2024-04-10 00:34:37] authn-users-500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-10 00:34:37,193 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/02_authn-users-500/stdout.txt is 8742 lines): 2024-04-10 00:34:37,193 DEBUG ----- output ----- 2024-04-10 00:34:37,193 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-10 00:34:37,194 INFO 00:31:53.288 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-10 00:34:37,194 INFO 00:31:59.941 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-10 00:34:37,194 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-10 00:34:37,194 INFO ^ 2024-04-10 00:34:37,194 INFO 00:31:59.944 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-10 00:34:37,194 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-10 00:34:37,194 INFO ^ 2024-04-10 00:34:37,194 INFO 00:32:02.519 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-10 00:34:37,194 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-10 00:34:37,194 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-10 00:34:37,194 INFO 2024-04-10 00:34:37,194 INFO ================================================================================ 2024-04-10 00:34:37,194 INFO 2024-04-10 00:32:11 5s elapsed 2024-04-10 00:34:37,194 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,194 INFO > Global (OK=208 KO=0 ) 2024-04-10 00:34:37,194 INFO > userRestLogin (OK=208 KO=0 ) 2024-04-10 00:34:37,194 INFO 2024-04-10 00:34:37,194 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,194 INFO [- ] 0% 2024-04-10 00:34:37,194 INFO waiting: 1805984 / active: 2 / done: 208 2024-04-10 00:34:37,194 INFO ================================================================================ 2024-04-10 00:34:37,194 INFO 2024-04-10 00:34:37,194 INFO 2024-04-10 00:34:37,194 INFO ================================================================================ 2024-04-10 00:34:37,194 INFO 2024-04-10 00:32:16 10s elapsed 2024-04-10 00:34:37,194 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,194 INFO > Global (OK=833 KO=0 ) 2024-04-10 00:34:37,194 INFO > userRestLogin (OK=833 KO=0 ) 2024-04-10 00:34:37,194 INFO 2024-04-10 00:34:37,194 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,194 INFO [- ] 0% 2024-04-10 00:34:37,194 INFO waiting: 1805359 / active: 2 / done: 833 2024-04-10 00:34:37,194 INFO ================================================================================ 2024-04-10 00:34:37,194 INFO 2024-04-10 00:34:37,194 INFO 2024-04-10 00:34:37,194 INFO ================================================================================ 2024-04-10 00:34:37,194 INFO 2024-04-10 00:32:21 15s elapsed 2024-04-10 00:34:37,194 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,195 INFO > Global (OK=1875 KO=0 ) 2024-04-10 00:34:37,195 INFO > userRestLogin (OK=1875 KO=0 ) 2024-04-10 00:34:37,195 INFO 2024-04-10 00:34:37,195 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,195 INFO [- ] 0% 2024-04-10 00:34:37,195 INFO waiting: 1804316 / active: 3 / done: 1875 2024-04-10 00:34:37,195 INFO ================================================================================ 2024-04-10 00:34:37,195 INFO 2024-04-10 00:34:37,195 INFO 2024-04-10 00:34:37,195 INFO ================================================================================ 2024-04-10 00:34:37,195 INFO 2024-04-10 00:32:26 20s elapsed 2024-04-10 00:34:37,195 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,195 INFO > Global (OK=3333 KO=0 ) 2024-04-10 00:34:37,195 INFO > userRestLogin (OK=3333 KO=0 ) 2024-04-10 00:34:37,195 INFO 2024-04-10 00:34:37,195 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,195 INFO [- ] 0% 2024-04-10 00:34:37,195 INFO waiting: 1802857 / active: 4 / done: 3333 2024-04-10 00:34:37,195 INFO ================================================================================ 2024-04-10 00:34:37,195 INFO 2024-04-10 00:34:37,195 INFO 2024-04-10 00:34:37,195 INFO ================================================================================ 2024-04-10 00:34:37,195 INFO 2024-04-10 00:32:31 25s elapsed 2024-04-10 00:34:37,195 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,195 INFO > Global (OK=5208 KO=0 ) 2024-04-10 00:34:37,195 INFO > userRestLogin (OK=5208 KO=0 ) 2024-04-10 00:34:37,195 INFO 2024-04-10 00:34:37,195 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,195 INFO [- ] 0% 2024-04-10 00:34:37,195 INFO waiting: 1800981 / active: 5 / done: 5208 2024-04-10 00:34:37,195 INFO ================================================================================ 2024-04-10 00:34:37,195 INFO 2024-04-10 00:34:37,195 INFO 2024-04-10 00:34:37,195 INFO ================================================================================ 2024-04-10 00:34:37,195 INFO 2024-04-10 00:32:36 30s elapsed 2024-04-10 00:34:37,195 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,196 INFO > Global (OK=7490 KO=0 ) 2024-04-10 00:34:37,196 INFO > userRestLogin (OK=7490 KO=0 ) 2024-04-10 00:34:37,196 INFO 2024-04-10 00:34:37,196 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,196 INFO [- ] 0% 2024-04-10 00:34:37,196 INFO waiting: 1798690 / active: 14 / done: 7490 2024-04-10 00:34:37,196 INFO ================================================================================ 2024-04-10 00:34:37,196 INFO 2024-04-10 00:34:37,196 INFO 2024-04-10 00:34:37,196 INFO ================================================================================ 2024-04-10 00:34:37,196 INFO 2024-04-10 00:32:41 35s elapsed 2024-04-10 00:34:37,196 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,196 INFO > Global (OK=10032 KO=0 ) 2024-04-10 00:34:37,196 INFO > userRestLogin (OK=10032 KO=0 ) 2024-04-10 00:34:37,196 INFO 2024-04-10 00:34:37,196 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,196 INFO [- ] 0% 2024-04-10 00:34:37,196 INFO waiting: 1796159 / active: 3 / done: 10032 2024-04-10 00:34:37,196 INFO ================================================================================ 2024-04-10 00:34:37,196 INFO 2024-04-10 00:34:37,196 INFO 2024-04-10 00:34:37,196 INFO ================================================================================ 2024-04-10 00:34:37,196 INFO 2024-04-10 00:32:46 40s elapsed 2024-04-10 00:34:37,196 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,196 INFO > Global (OK=12584 KO=0 ) 2024-04-10 00:34:37,196 INFO [...] 2024-04-10 00:34:37,199 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,199 INFO > Global (OK=1791058 KO=0 ) 2024-04-10 00:34:37,199 INFO > userRestLogin (OK=1791058 KO=0 ) 2024-04-10 00:34:37,199 INFO 2024-04-10 00:34:37,199 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,199 INFO [#########################################################################-] 99% 2024-04-10 00:34:37,199 INFO waiting: 15134 / active: 2 / done: 1791058 2024-04-10 00:34:37,199 INFO ================================================================================ 2024-04-10 00:34:37,199 INFO 2024-04-10 00:34:37,199 INFO 2024-04-10 00:34:37,199 INFO ================================================================================ 2024-04-10 00:34:37,199 INFO 2024-04-10 01:32:11 3605s elapsed 2024-04-10 00:34:37,199 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,199 INFO > Global (OK=1793589 KO=0 ) 2024-04-10 00:34:37,199 INFO > userRestLogin (OK=1793589 KO=0 ) 2024-04-10 00:34:37,199 INFO 2024-04-10 00:34:37,199 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,199 INFO [#########################################################################-] 99% 2024-04-10 00:34:37,199 INFO waiting: 12600 / active: 5 / done: 1793589 2024-04-10 00:34:37,199 INFO ================================================================================ 2024-04-10 00:34:37,199 INFO 2024-04-10 00:34:37,199 INFO 2024-04-10 00:34:37,200 INFO ================================================================================ 2024-04-10 00:34:37,200 INFO 2024-04-10 01:32:16 3610s elapsed 2024-04-10 00:34:37,200 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,200 INFO > Global (OK=1796194 KO=0 ) 2024-04-10 00:34:37,200 INFO > userRestLogin (OK=1796194 KO=0 ) 2024-04-10 00:34:37,200 INFO 2024-04-10 00:34:37,200 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,200 INFO [#########################################################################-] 99% 2024-04-10 00:34:37,200 INFO waiting: 9996 / active: 4 / done: 1796194 2024-04-10 00:34:37,200 INFO ================================================================================ 2024-04-10 00:34:37,200 INFO 2024-04-10 00:34:37,200 INFO 2024-04-10 00:34:37,200 INFO ================================================================================ 2024-04-10 00:34:37,200 INFO 2024-04-10 01:32:21 3615s elapsed 2024-04-10 00:34:37,200 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,200 INFO > Global (OK=1798729 KO=0 ) 2024-04-10 00:34:37,200 INFO > userRestLogin (OK=1798729 KO=0 ) 2024-04-10 00:34:37,200 INFO 2024-04-10 00:34:37,200 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,200 INFO [#########################################################################-] 99% 2024-04-10 00:34:37,200 INFO waiting: 7457 / active: 8 / done: 1798729 2024-04-10 00:34:37,200 INFO ================================================================================ 2024-04-10 00:34:37,200 INFO 2024-04-10 00:34:37,200 INFO 2024-04-10 00:34:37,200 INFO ================================================================================ 2024-04-10 00:34:37,200 INFO 2024-04-10 01:32:26 3620s elapsed 2024-04-10 00:34:37,200 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,200 INFO > Global (OK=1801235 KO=0 ) 2024-04-10 00:34:37,200 INFO > userRestLogin (OK=1801235 KO=0 ) 2024-04-10 00:34:37,200 INFO 2024-04-10 00:34:37,200 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,200 INFO [#########################################################################-] 99% 2024-04-10 00:34:37,200 INFO waiting: 4953 / active: 6 / done: 1801235 2024-04-10 00:34:37,200 INFO ================================================================================ 2024-04-10 00:34:37,200 INFO 2024-04-10 00:34:37,200 INFO 2024-04-10 00:34:37,200 INFO ================================================================================ 2024-04-10 00:34:37,200 INFO 2024-04-10 01:32:31 3625s elapsed 2024-04-10 00:34:37,200 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,201 INFO > Global (OK=1803747 KO=0 ) 2024-04-10 00:34:37,201 INFO > userRestLogin (OK=1803747 KO=0 ) 2024-04-10 00:34:37,201 INFO 2024-04-10 00:34:37,201 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,201 INFO [#########################################################################-] 99% 2024-04-10 00:34:37,201 INFO waiting: 2445 / active: 2 / done: 1803747 2024-04-10 00:34:37,201 INFO ================================================================================ 2024-04-10 00:34:37,201 INFO 2024-04-10 00:34:37,201 INFO 2024-04-10 00:34:37,201 INFO ================================================================================ 2024-04-10 00:34:37,201 INFO 2024-04-10 01:32:36 3630s elapsed 2024-04-10 00:34:37,201 INFO ---- Requests ------------------------------------------------------------------ 2024-04-10 00:34:37,201 INFO > Global (OK=1806194 KO=0 ) 2024-04-10 00:34:37,201 INFO > userRestLogin (OK=1806194 KO=0 ) 2024-04-10 00:34:37,201 INFO 2024-04-10 00:34:37,201 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-10 00:34:37,201 INFO [##########################################################################]100% 2024-04-10 00:34:37,201 INFO waiting: 0 / active: 0 / done: 1806194 2024-04-10 00:34:37,201 INFO ================================================================================ 2024-04-10 00:34:37,201 INFO 2024-04-10 00:34:37,201 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-10 00:34:37,201 INFO 2024-04-10 00:34:37,201 INFO ---------------------- 2024-04-10 00:34:37,201 INFO [10/Apr/2024 01:32:36] Create tar file authn-users-500.tar.gz 2024-04-10 00:34:37,201 INFO ---------------------- 2024-04-10 00:34:37,201 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-10 00:34:37,201 INFO RESULT_FOLDER=authn-users-500 2024-04-10 00:34:37,201 INFO SIMULATION_PATH=authn-users-500/loadtest-20240409233205948 2024-04-10 00:34:37,201 INFO PWD=/results/gatling/authn-users-500/loadtest-20240409233205948 2024-04-10 00:34:37,201 INFO [cmd] tar -czvf /results/gatling//authn-users-500.tar.gz --exclude=simulation.log * 2024-04-10 00:34:37,201 INFO /results/gatling 2024-04-10 00:34:37,201 INFO 2024-04-10 00:34:37,201 INFO ---------------------- 2024-04-10 00:34:37,201 INFO [10/Apr/2024 01:32:36] Update /results/gatling//latest 2024-04-10 00:34:37,201 INFO ---------------------- 2024-04-10 00:34:37,201 INFO 2024-04-10 00:34:37,201 INFO ---------------------- 2024-04-10 00:34:37,201 INFO [10/Apr/2024 01:32:36] Update /results/gatling//latest.tar.gz 2024-04-10 00:34:37,201 INFO ---------------------- 2024-04-10 00:34:37,202 INFO ________________________________________________________________________________ [2024-04-10 00:34:37] authn-users-500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-10 00:34:37,202 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-500/stderr.txt is small (0 lines), printing all lines: 2024-04-10 00:34:37,202 DEBUG ----- output ----- 2024-04-10 00:34:37,202 DEBUG 2024-04-10 00:34:37,202 INFO ________________________________________________________________________________ [2024-04-10 00:34:37] authn-users-500 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-10 00:34:37] authn-users-500 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-10 00:34:37] authn-users-500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped