--Task-- name: authn-users-100 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: [] preceding_task: None options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 100, 'rampup-duration': 30, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-09 22:28:54] authn-users-100 pre : N/A ________________________________________________________________________________ [2024-04-09 22:28:54] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-09 22:28:54] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-100/stdout.txt Pod log : /results/orders/authn-users-100 2024-04-09 22:28:54,816 INFO 2024-04-09 22:28:54,817 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 22:28:54,817 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 22:28:55,123 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 22:28:55,124 INFO [loop_until]: OK (rc = 0) 2024-04-09 22:28:55,124 DEBUG --- stdout --- 2024-04-09 22:28:55,124 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 22:28:55,124 DEBUG --- stderr --- 2024-04-09 22:28:55,124 DEBUG [2024-04-09 22:28:55] - INFO: Get overseer pod resources requests memory 2024-04-09 22:28:55,124 INFO 2024-04-09 22:28:55,124 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-6f8784665d-zq5hb --output jsonpath={.spec.containers[].resources.requests.memory} 2024-04-09 22:28:55,124 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-09 22:28:55,427 INFO [loop_until]: OK (rc = 0) 2024-04-09 22:28:55,427 DEBUG --- stdout --- 2024-04-09 22:28:55,427 DEBUG 4Gi 2024-04-09 22:28:55,427 DEBUG --- stderr --- 2024-04-09 22:28:55,427 DEBUG ________________________________________________________________________________ [2024-04-09 22:28:55] authn-users-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-09 22:28:55,428 INFO 2024-04-09 22:28:55,428 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 22:28:55,428 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 22:28:55,753 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 22:28:55,754 INFO [loop_until]: OK (rc = 0) 2024-04-09 22:28:55,754 DEBUG --- stdout --- 2024-04-09 22:28:55,754 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 22:28:55,754 DEBUG --- stderr --- 2024-04-09 22:28:55,754 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-100 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-09 22:28:55] authn-users-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-09 22:28:55,755 INFO 2024-04-09 22:28:55,755 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 22:28:55,755 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 22:28:56,080 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 22:28:56,080 INFO [loop_until]: OK (rc = 0) 2024-04-09 22:28:56,080 DEBUG --- stdout --- 2024-04-09 22:28:56,080 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 22:28:56,080 DEBUG --- stderr --- 2024-04-09 22:28:56,080 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/order.json { "name": "authn-users-100", "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-100 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-100", "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=100 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-09 22:28:56] authn-users-100 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 22:28:56,082 INFO 2024-04-09 22:28:56,082 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-09 22:28:56,082 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-09 22:28:56,324 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-09 22:28:56,325 INFO [loop_until]: OK (rc = 0) 2024-04-09 22:28:56,325 DEBUG --- stdout --- 2024-04-09 22:28:56,325 DEBUG WAITING 2024-04-09 22:28:56,325 DEBUG --- stderr --- 2024-04-09 22:28:56,325 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 22:28:56] authn-users-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-09 22:28:56,326 INFO 2024-04-09 22:28:56,326 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 22:28:56,326 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 22:28:56,651 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 22:28:56,651 INFO [loop_until]: OK (rc = 0) 2024-04-09 22:28:56,652 DEBUG --- stdout --- 2024-04-09 22:28:56,652 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 22:28:56,652 DEBUG --- stderr --- 2024-04-09 22:28:56,652 DEBUG 2024-04-09 22:28:56,652 INFO 2024-04-09 22:28:56,652 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/01_authn-users-100/order.json overseer-0-6f8784665d-zq5hb:/results/orders/order.json 2024-04-09 22:28:56,652 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-09 22:28:58,081 INFO [loop_until]: OK (rc = 0) 2024-04-09 22:28:58,081 DEBUG --- stdout --- 2024-04-09 22:28:58,081 DEBUG 2024-04-09 22:28:58,081 DEBUG --- stderr --- 2024-04-09 22:28:58,081 DEBUG Order has been successfully forwarded to overseer [2024-04-09 22:28:58] - INFO: Check if Gatling simulation started successfully 2024-04-09 22:28:58,082 INFO 2024-04-09 22:28:58,082 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 22:28:58,082 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 22:28:58,402 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 22:28:58,402 INFO [loop_until]: OK (rc = 0) 2024-04-09 22:28:58,402 DEBUG --- stdout --- 2024-04-09 22:28:58,402 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 22:28:58,402 DEBUG --- stderr --- 2024-04-09 22:28:58,402 DEBUG 2024-04-09 22:28:58,402 INFO 2024-04-09 22:28:58,402 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-100/stdout.txt | grep "started..." 2024-04-09 22:28:58,402 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-09 22:28:59,183 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-09 22:29:05,005 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-09 22:29:10,851 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-09 22:29:16,640 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-09 22:29:16,641 INFO [loop_until]: OK (rc = 0) 2024-04-09 22:29:16,641 DEBUG --- stdout --- 2024-04-09 22:29:16,641 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 23:29:00.890 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 23:29:07.197 [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"); ^ 23:29:07.203 [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"); ^ 23:29:09.633 [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 22:29:16,641 DEBUG --- stderr --- 2024-04-09 22:29:16,641 DEBUG ________________________________________________________________________________ [2024-04-09 22:29:16] authn-users-100 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 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 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-09 23:31:27] authn-users-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-09 23:31:27,374 INFO 2024-04-09 23:31:27,374 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:27,374 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:27,754 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:27,754 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:27,754 DEBUG --- stdout --- 2024-04-09 23:31:27,754 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:27,754 DEBUG --- stderr --- 2024-04-09 23:31:27,754 DEBUG 2024-04-09 23:31:27,755 INFO 2024-04-09 23:31:27,755 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-09 23:31:27,755 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-09 23:31:28,617 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:28,617 DEBUG --- stdout --- 2024-04-09 23:31:28,617 DEBUG 2024-04-09 23:31:28,617 DEBUG --- stderr --- 2024-04-09 23:31:28,617 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-09 23:31:28] authn-users-100 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-09 23:31:28] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/order.json 2024-04-09 23:31:28,618 INFO 2024-04-09 23:31:28,618 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-09 23:31:28,618 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:28,944 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:28,944 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:28,945 DEBUG --- stdout --- 2024-04-09 23:31:28,945 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:28,945 DEBUG --- stderr --- 2024-04-09 23:31:28,945 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-09 23:31:28,945 INFO 2024-04-09 23:31:28,945 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:28,945 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:29,257 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:29,257 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:29,258 DEBUG --- stdout --- 2024-04-09 23:31:29,258 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:29,258 DEBUG --- stderr --- 2024-04-09 23:31:29,258 DEBUG 2024-04-09 23:31:29,258 INFO 2024-04-09 23:31:29,258 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-100/rc.txt" ]; then echo "/results/orders/authn-users-100/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-100/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/rc.txt file found" 2024-04-09 23:31:29,258 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-09 23:31:30,087 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-09 23:31:30,087 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:30,087 DEBUG --- stdout --- 2024-04-09 23:31:30,087 DEBUG /results/orders/authn-users-100/rc.txt file found 2024-04-09 23:31:30,087 DEBUG --- stderr --- 2024-04-09 23:31:30,087 DEBUG 2024-04-09 23:31:30,088 INFO 2024-04-09 23:31:30,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-09 23:31:30,088 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:30,420 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:30,421 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:30,421 DEBUG --- stdout --- 2024-04-09 23:31:30,421 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:30,421 DEBUG --- stderr --- 2024-04-09 23:31:30,421 DEBUG 2024-04-09 23:31:30,421 INFO 2024-04-09 23:31:30,421 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-100/rc.txt 2024-04-09 23:31:31,269 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-09 23:31:31,269 DEBUG --- stdout --- 2024-04-09 23:31:31,269 DEBUG 4 /results/orders/authn-users-100/rc.txt 2024-04-09 23:31:31,269 DEBUG --- stderr --- 2024-04-09 23:31:31,269 DEBUG 2024-04-09 23:31:31,269 INFO - Download file (size 4k) 2024-04-09 23:31:31,270 INFO 2024-04-09 23:31:31,270 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:31,270 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:31,601 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:31,602 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:31,602 DEBUG --- stdout --- 2024-04-09 23:31:31,602 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:31,602 DEBUG --- stderr --- 2024-04-09 23:31:31,602 DEBUG source : /results/orders/authn-users-100/rc.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-09 23:31:31,602 INFO 2024-04-09 23:31:31,602 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:31,602 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:31,974 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:31,974 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:31,975 DEBUG --- stdout --- 2024-04-09 23:31:31,975 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:31,975 DEBUG --- stderr --- 2024-04-09 23:31:31,975 DEBUG 2024-04-09 23:31:31,975 INFO 2024-04-09 23:31:31,975 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-100/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-09 23:31:31,975 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-09 23:31:32,747 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:32,747 DEBUG --- stdout --- 2024-04-09 23:31:32,747 DEBUG tar: Removing leading `/' from member names 2024-04-09 23:31:32,747 DEBUG --- stderr --- 2024-04-09 23:31:32,747 DEBUG 2024-04-09 23:31:32,748 INFO 2024-04-09 23:31:32,748 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:32,748 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:33,057 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:33,058 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:33,058 DEBUG --- stdout --- 2024-04-09 23:31:33,058 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:33,058 DEBUG --- stderr --- 2024-04-09 23:31:33,058 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-09 23:31:33,058 INFO 2024-04-09 23:31:33,058 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:33,058 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:33,370 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:33,371 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:33,371 DEBUG --- stdout --- 2024-04-09 23:31:33,371 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:33,371 DEBUG --- stderr --- 2024-04-09 23:31:33,371 DEBUG 2024-04-09 23:31:33,371 INFO 2024-04-09 23:31:33,371 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-100/stderr.txt" ]; then echo "/results/orders/authn-users-100/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-100/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/stderr.txt file found" 2024-04-09 23:31:33,371 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-09 23:31:34,194 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-09 23:31:34,196 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:34,196 DEBUG --- stdout --- 2024-04-09 23:31:34,197 DEBUG /results/orders/authn-users-100/stderr.txt file found 2024-04-09 23:31:34,197 DEBUG --- stderr --- 2024-04-09 23:31:34,197 DEBUG 2024-04-09 23:31:34,197 INFO 2024-04-09 23:31:34,197 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-09 23:31:34,197 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:34,543 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:34,543 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:34,543 DEBUG --- stdout --- 2024-04-09 23:31:34,543 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:34,543 DEBUG --- stderr --- 2024-04-09 23:31:34,543 DEBUG 2024-04-09 23:31:34,544 INFO 2024-04-09 23:31:34,544 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-100/stderr.txt 2024-04-09 23:31:35,446 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-09 23:31:35,447 DEBUG --- stdout --- 2024-04-09 23:31:35,447 DEBUG 0 /results/orders/authn-users-100/stderr.txt 2024-04-09 23:31:35,447 DEBUG --- stderr --- 2024-04-09 23:31:35,447 DEBUG 2024-04-09 23:31:35,447 INFO - Download file (size 0k) 2024-04-09 23:31:35,447 INFO 2024-04-09 23:31:35,447 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:35,447 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:35,776 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:35,776 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:35,777 DEBUG --- stdout --- 2024-04-09 23:31:35,777 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:35,777 DEBUG --- stderr --- 2024-04-09 23:31:35,777 DEBUG source : /results/orders/authn-users-100/stderr.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-09 23:31:35,777 INFO 2024-04-09 23:31:35,777 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:35,777 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:36,097 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:36,097 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:36,097 DEBUG --- stdout --- 2024-04-09 23:31:36,097 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:36,097 DEBUG --- stderr --- 2024-04-09 23:31:36,097 DEBUG 2024-04-09 23:31:36,097 INFO 2024-04-09 23:31:36,098 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-100/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-09 23:31:36,098 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-09 23:31:36,849 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:36,849 DEBUG --- stdout --- 2024-04-09 23:31:36,849 DEBUG tar: Removing leading `/' from member names 2024-04-09 23:31:36,849 DEBUG --- stderr --- 2024-04-09 23:31:36,849 DEBUG 2024-04-09 23:31:36,850 INFO 2024-04-09 23:31:36,850 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:36,850 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:37,174 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:37,175 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:37,175 DEBUG --- stdout --- 2024-04-09 23:31:37,175 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:37,175 DEBUG --- stderr --- 2024-04-09 23:31:37,175 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-09 23:31:37,175 INFO 2024-04-09 23:31:37,175 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:37,175 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:37,506 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:37,506 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:37,506 DEBUG --- stdout --- 2024-04-09 23:31:37,506 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:37,506 DEBUG --- stderr --- 2024-04-09 23:31:37,506 DEBUG 2024-04-09 23:31:37,507 INFO 2024-04-09 23:31:37,507 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-100/stdout.txt" ]; then echo "/results/orders/authn-users-100/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-100/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/stdout.txt file found" 2024-04-09 23:31:37,507 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-09 23:31:38,386 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-09 23:31:38,386 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:38,386 DEBUG --- stdout --- 2024-04-09 23:31:38,386 DEBUG /results/orders/authn-users-100/stdout.txt file found 2024-04-09 23:31:38,386 DEBUG --- stderr --- 2024-04-09 23:31:38,387 DEBUG 2024-04-09 23:31:38,387 INFO 2024-04-09 23:31:38,387 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:38,387 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:38,774 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:38,774 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:38,774 DEBUG --- stdout --- 2024-04-09 23:31:38,774 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:38,774 DEBUG --- stderr --- 2024-04-09 23:31:38,775 DEBUG 2024-04-09 23:31:38,775 INFO 2024-04-09 23:31:38,775 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-100/stdout.txt 2024-04-09 23:31:39,664 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-09 23:31:39,665 DEBUG --- stdout --- 2024-04-09 23:31:39,665 DEBUG 504 /results/orders/authn-users-100/stdout.txt 2024-04-09 23:31:39,665 DEBUG --- stderr --- 2024-04-09 23:31:39,665 DEBUG 2024-04-09 23:31:39,665 INFO - Download file (size 504k) 2024-04-09 23:31:39,666 INFO 2024-04-09 23:31:39,666 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:39,666 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:40,014 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:40,014 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:40,014 DEBUG --- stdout --- 2024-04-09 23:31:40,014 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:40,014 DEBUG --- stderr --- 2024-04-09 23:31:40,014 DEBUG source : /results/orders/authn-users-100/stdout.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-09 23:31:40,015 INFO 2024-04-09 23:31:40,015 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:40,015 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:40,372 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:40,372 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:40,373 DEBUG --- stdout --- 2024-04-09 23:31:40,373 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:40,373 DEBUG --- stderr --- 2024-04-09 23:31:40,373 DEBUG 2024-04-09 23:31:40,373 INFO 2024-04-09 23:31:40,374 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-100/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-09 23:31:40,374 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-09 23:31:41,502 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:41,502 DEBUG --- stdout --- 2024-04-09 23:31:41,503 DEBUG tar: Removing leading `/' from member names 2024-04-09 23:31:41,503 DEBUG --- stderr --- 2024-04-09 23:31:41,503 DEBUG ________________________________________________________________________________ [2024-04-09 23:31:41] authn-users-100 show_cmd : Display order command ________________________________________________________________________________ 2024-04-09 23:31:41,503 INFO 2024-04-09 23:31:41,503 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:41,503 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:41,835 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:41,835 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:41,835 DEBUG --- stdout --- 2024-04-09 23:31:41,835 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:41,835 DEBUG --- stderr --- 2024-04-09 23:31:41,835 DEBUG Checking pod: overseer-0-6f8784665d-zq5hb for file exists, if not wait. 2024-04-09 23:31:41,836 INFO 2024-04-09 23:31:41,836 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:41,836 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:42,219 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:42,219 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:42,219 DEBUG --- stdout --- 2024-04-09 23:31:42,219 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:42,219 DEBUG --- stderr --- 2024-04-09 23:31:42,219 DEBUG 2024-04-09 23:31:42,220 INFO 2024-04-09 23:31:42,220 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-100/cmd.txt" ]; then echo "/results/orders/authn-users-100/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-100/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/cmd.txt file found" 2024-04-09 23:31:42,220 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-09 23:31:43,071 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-09 23:31:43,071 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:43,071 DEBUG --- stdout --- 2024-04-09 23:31:43,071 DEBUG /results/orders/authn-users-100/cmd.txt file found 2024-04-09 23:31:43,071 DEBUG --- stderr --- 2024-04-09 23:31:43,071 DEBUG 2024-04-09 23:31:43,071 INFO 2024-04-09 23:31:43,071 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:43,072 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:43,401 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:43,401 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:43,402 DEBUG --- stdout --- 2024-04-09 23:31:43,402 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:43,402 DEBUG --- stderr --- 2024-04-09 23:31:43,402 DEBUG 2024-04-09 23:31:43,402 INFO 2024-04-09 23:31:43,402 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-100/cmd.txt 2024-04-09 23:31:44,285 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-09 23:31:44,285 DEBUG --- stdout --- 2024-04-09 23:31:44,285 DEBUG 4 /results/orders/authn-users-100/cmd.txt 2024-04-09 23:31:44,285 DEBUG --- stderr --- 2024-04-09 23:31:44,285 DEBUG 2024-04-09 23:31:44,285 INFO - Download file (size 4k) 2024-04-09 23:31:44,286 INFO 2024-04-09 23:31:44,286 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:44,286 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:44,597 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:44,597 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:44,597 DEBUG --- stdout --- 2024-04-09 23:31:44,597 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:44,597 DEBUG --- stderr --- 2024-04-09 23:31:44,597 DEBUG source : /results/orders/authn-users-100/cmd.txt @ overseer-0-6f8784665d-zq5hb destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-09 23:31:44,598 INFO 2024-04-09 23:31:44,598 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:44,598 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-09 23:31:44,915 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-09 23:31:44,918 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:44,918 DEBUG --- stdout --- 2024-04-09 23:31:44,918 DEBUG overseer-0-6f8784665d-zq5hb 2024-04-09 23:31:44,918 DEBUG --- stderr --- 2024-04-09 23:31:44,918 DEBUG 2024-04-09 23:31:44,919 INFO 2024-04-09 23:31:44,919 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-100/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-09 23:31:44,919 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-09 23:31:45,728 INFO [loop_until]: OK (rc = 0) 2024-04-09 23:31:45,729 DEBUG --- stdout --- 2024-04-09 23:31:45,729 DEBUG tar: Removing leading `/' from member names 2024-04-09 23:31:45,729 DEBUG --- stderr --- 2024-04-09 23:31:45,729 DEBUG 2024-04-09 23:31:45,729 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt is small (1 lines), printing all lines: 2024-04-09 23:31:45,729 DEBUG ----- output ----- 2024-04-09 23:31:45,729 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-100 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-09 23:31:45,729 INFO ________________________________________________________________________________ [2024-04-09 23:31:45] authn-users-100 show_rc : Display rc order ________________________________________________________________________________ 2024-04-09 23:31:45,730 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt is small (1 lines), printing all lines: 2024-04-09 23:31:45,730 DEBUG ----- output ----- 2024-04-09 23:31:45,731 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-09 23:31:45,731 INFO ________________________________________________________________________________ [2024-04-09 23:31:45] authn-users-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-09 23:31:45,733 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/01_authn-users-100/stdout.txt is 8742 lines): 2024-04-09 23:31:45,733 DEBUG ----- output ----- 2024-04-09 23:31:45,733 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-09 23:31:45,733 INFO 23:29:00.890 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-09 23:31:45,733 INFO 23:29:07.197 [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-09 23:31:45,733 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-09 23:31:45,733 INFO ^ 2024-04-09 23:31:45,733 INFO 23:29:07.203 [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-09 23:31:45,733 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-09 23:31:45,733 INFO ^ 2024-04-09 23:31:45,733 INFO 23:29:09.633 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-09 23:31:45,734 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-09 23:31:45,734 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-09 23:31:45,734 INFO 2024-04-09 23:31:45,734 INFO ================================================================================ 2024-04-09 23:31:45,734 INFO 2024-04-09 23:29:18 5s elapsed 2024-04-09 23:31:45,734 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,734 INFO > Global (OK=41 KO=0 ) 2024-04-09 23:31:45,734 INFO > userRestLogin (OK=41 KO=0 ) 2024-04-09 23:31:45,734 INFO 2024-04-09 23:31:45,734 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,734 INFO [- ] 0% 2024-04-09 23:31:45,734 INFO waiting: 361358 / active: 1 / done: 41 2024-04-09 23:31:45,734 INFO ================================================================================ 2024-04-09 23:31:45,734 INFO 2024-04-09 23:31:45,734 INFO 2024-04-09 23:31:45,734 INFO ================================================================================ 2024-04-09 23:31:45,734 INFO 2024-04-09 23:29:23 10s elapsed 2024-04-09 23:31:45,734 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,734 INFO > Global (OK=166 KO=0 ) 2024-04-09 23:31:45,734 INFO > userRestLogin (OK=166 KO=0 ) 2024-04-09 23:31:45,734 INFO 2024-04-09 23:31:45,734 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,734 INFO [- ] 0% 2024-04-09 23:31:45,735 INFO waiting: 361233 / active: 1 / done: 166 2024-04-09 23:31:45,735 INFO ================================================================================ 2024-04-09 23:31:45,735 INFO 2024-04-09 23:31:45,735 INFO 2024-04-09 23:31:45,735 INFO ================================================================================ 2024-04-09 23:31:45,735 INFO 2024-04-09 23:29:28 15s elapsed 2024-04-09 23:31:45,735 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,735 INFO > Global (OK=375 KO=0 ) 2024-04-09 23:31:45,735 INFO > userRestLogin (OK=375 KO=0 ) 2024-04-09 23:31:45,735 INFO 2024-04-09 23:31:45,735 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,735 INFO [- ] 0% 2024-04-09 23:31:45,735 INFO waiting: 361024 / active: 1 / done: 375 2024-04-09 23:31:45,735 INFO ================================================================================ 2024-04-09 23:31:45,735 INFO 2024-04-09 23:31:45,735 INFO 2024-04-09 23:31:45,735 INFO ================================================================================ 2024-04-09 23:31:45,735 INFO 2024-04-09 23:29:33 20s elapsed 2024-04-09 23:31:45,735 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,735 INFO > Global (OK=666 KO=0 ) 2024-04-09 23:31:45,735 INFO > userRestLogin (OK=666 KO=0 ) 2024-04-09 23:31:45,735 INFO 2024-04-09 23:31:45,735 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,735 INFO [- ] 0% 2024-04-09 23:31:45,736 INFO waiting: 360733 / active: 1 / done: 666 2024-04-09 23:31:45,736 INFO ================================================================================ 2024-04-09 23:31:45,736 INFO 2024-04-09 23:31:45,736 INFO 2024-04-09 23:31:45,736 INFO ================================================================================ 2024-04-09 23:31:45,736 INFO 2024-04-09 23:29:38 25s elapsed 2024-04-09 23:31:45,736 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,736 INFO > Global (OK=1041 KO=0 ) 2024-04-09 23:31:45,736 INFO > userRestLogin (OK=1041 KO=0 ) 2024-04-09 23:31:45,736 INFO 2024-04-09 23:31:45,736 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,736 INFO [- ] 0% 2024-04-09 23:31:45,736 INFO waiting: 360358 / active: 1 / done: 1041 2024-04-09 23:31:45,736 INFO ================================================================================ 2024-04-09 23:31:45,736 INFO 2024-04-09 23:31:45,736 INFO 2024-04-09 23:31:45,736 INFO ================================================================================ 2024-04-09 23:31:45,736 INFO 2024-04-09 23:29:43 30s elapsed 2024-04-09 23:31:45,736 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,736 INFO > Global (OK=1499 KO=0 ) 2024-04-09 23:31:45,736 INFO > userRestLogin (OK=1499 KO=0 ) 2024-04-09 23:31:45,736 INFO 2024-04-09 23:31:45,736 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,736 INFO [- ] 0% 2024-04-09 23:31:45,737 INFO waiting: 359900 / active: 1 / done: 1499 2024-04-09 23:31:45,737 INFO ================================================================================ 2024-04-09 23:31:45,737 INFO 2024-04-09 23:31:45,737 INFO 2024-04-09 23:31:45,737 INFO ================================================================================ 2024-04-09 23:31:45,737 INFO 2024-04-09 23:29:48 35s elapsed 2024-04-09 23:31:45,737 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,737 INFO > Global (OK=2020 KO=0 ) 2024-04-09 23:31:45,737 INFO > userRestLogin (OK=2020 KO=0 ) 2024-04-09 23:31:45,737 INFO 2024-04-09 23:31:45,737 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,737 INFO [- ] 0% 2024-04-09 23:31:45,737 INFO waiting: 359377 / active: 3 / done: 2020 2024-04-09 23:31:45,737 INFO ================================================================================ 2024-04-09 23:31:45,737 INFO 2024-04-09 23:31:45,737 INFO 2024-04-09 23:31:45,737 INFO ================================================================================ 2024-04-09 23:31:45,737 INFO 2024-04-09 23:29:53 40s elapsed 2024-04-09 23:31:45,737 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,737 INFO > Global (OK=2510 KO=0 ) 2024-04-09 23:31:45,737 INFO [...] 2024-04-09 23:31:45,741 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,742 INFO > Global (OK=358415 KO=0 ) 2024-04-09 23:31:45,742 INFO > userRestLogin (OK=358415 KO=0 ) 2024-04-09 23:31:45,742 INFO 2024-04-09 23:31:45,742 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,742 INFO [######################################################################### ] 99% 2024-04-09 23:31:45,742 INFO waiting: 2985 / active: 0 / done: 358415 2024-04-09 23:31:45,742 INFO ================================================================================ 2024-04-09 23:31:45,742 INFO 2024-04-09 23:31:45,742 INFO 2024-04-09 23:31:45,742 INFO ================================================================================ 2024-04-09 23:31:45,742 INFO 2024-04-10 00:29:18 3605s elapsed 2024-04-09 23:31:45,742 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,742 INFO > Global (OK=358908 KO=0 ) 2024-04-09 23:31:45,742 INFO > userRestLogin (OK=358908 KO=0 ) 2024-04-09 23:31:45,742 INFO 2024-04-09 23:31:45,742 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,742 INFO [#########################################################################-] 99% 2024-04-09 23:31:45,742 INFO waiting: 2490 / active: 2 / done: 358908 2024-04-09 23:31:45,742 INFO ================================================================================ 2024-04-09 23:31:45,742 INFO 2024-04-09 23:31:45,742 INFO 2024-04-09 23:31:45,742 INFO ================================================================================ 2024-04-09 23:31:45,743 INFO 2024-04-10 00:29:23 3610s elapsed 2024-04-09 23:31:45,743 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,743 INFO > Global (OK=359433 KO=0 ) 2024-04-09 23:31:45,743 INFO > userRestLogin (OK=359433 KO=0 ) 2024-04-09 23:31:45,743 INFO 2024-04-09 23:31:45,743 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,743 INFO [#########################################################################-] 99% 2024-04-09 23:31:45,743 INFO waiting: 1966 / active: 1 / done: 359433 2024-04-09 23:31:45,743 INFO ================================================================================ 2024-04-09 23:31:45,743 INFO 2024-04-09 23:31:45,743 INFO 2024-04-09 23:31:45,743 INFO ================================================================================ 2024-04-09 23:31:45,743 INFO 2024-04-10 00:29:28 3615s elapsed 2024-04-09 23:31:45,743 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,743 INFO > Global (OK=359942 KO=0 ) 2024-04-09 23:31:45,743 INFO > userRestLogin (OK=359942 KO=0 ) 2024-04-09 23:31:45,743 INFO 2024-04-09 23:31:45,743 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,743 INFO [#########################################################################-] 99% 2024-04-09 23:31:45,743 INFO waiting: 1457 / active: 1 / done: 359942 2024-04-09 23:31:45,743 INFO ================================================================================ 2024-04-09 23:31:45,743 INFO 2024-04-09 23:31:45,744 INFO 2024-04-09 23:31:45,744 INFO ================================================================================ 2024-04-09 23:31:45,744 INFO 2024-04-10 00:29:33 3620s elapsed 2024-04-09 23:31:45,744 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,744 INFO > Global (OK=360429 KO=0 ) 2024-04-09 23:31:45,744 INFO > userRestLogin (OK=360429 KO=0 ) 2024-04-09 23:31:45,744 INFO 2024-04-09 23:31:45,744 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,744 INFO [######################################################################### ] 99% 2024-04-09 23:31:45,744 INFO waiting: 971 / active: 0 / done: 360429 2024-04-09 23:31:45,744 INFO ================================================================================ 2024-04-09 23:31:45,744 INFO 2024-04-09 23:31:45,744 INFO 2024-04-09 23:31:45,744 INFO ================================================================================ 2024-04-09 23:31:45,744 INFO 2024-04-10 00:29:38 3625s elapsed 2024-04-09 23:31:45,744 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,744 INFO > Global (OK=360911 KO=0 ) 2024-04-09 23:31:45,744 INFO > userRestLogin (OK=360911 KO=0 ) 2024-04-09 23:31:45,744 INFO 2024-04-09 23:31:45,744 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,744 INFO [#########################################################################-] 99% 2024-04-09 23:31:45,744 INFO waiting: 488 / active: 1 / done: 360911 2024-04-09 23:31:45,744 INFO ================================================================================ 2024-04-09 23:31:45,744 INFO 2024-04-09 23:31:45,745 INFO 2024-04-09 23:31:45,745 INFO ================================================================================ 2024-04-09 23:31:45,745 INFO 2024-04-10 00:29:43 3630s elapsed 2024-04-09 23:31:45,745 INFO ---- Requests ------------------------------------------------------------------ 2024-04-09 23:31:45,745 INFO > Global (OK=361400 KO=0 ) 2024-04-09 23:31:45,745 INFO > userRestLogin (OK=361400 KO=0 ) 2024-04-09 23:31:45,745 INFO 2024-04-09 23:31:45,745 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-09 23:31:45,745 INFO [##########################################################################]100% 2024-04-09 23:31:45,745 INFO waiting: 0 / active: 0 / done: 361400 2024-04-09 23:31:45,745 INFO ================================================================================ 2024-04-09 23:31:45,745 INFO 2024-04-09 23:31:45,745 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-09 23:31:45,745 INFO 2024-04-09 23:31:45,745 INFO ---------------------- 2024-04-09 23:31:45,745 INFO [10/Apr/2024 00:29:43] Create tar file authn-users-100.tar.gz 2024-04-09 23:31:45,745 INFO ---------------------- 2024-04-09 23:31:45,745 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-09 23:31:45,745 INFO RESULT_FOLDER=authn-users-100 2024-04-09 23:31:45,745 INFO SIMULATION_PATH=authn-users-100/loadtest-20240409222912932 2024-04-09 23:31:45,745 INFO PWD=/results/gatling/authn-users-100/loadtest-20240409222912932 2024-04-09 23:31:45,745 INFO [cmd] tar -czvf /results/gatling//authn-users-100.tar.gz --exclude=simulation.log * 2024-04-09 23:31:45,745 INFO /results/gatling 2024-04-09 23:31:45,745 INFO 2024-04-09 23:31:45,745 INFO ---------------------- 2024-04-09 23:31:45,745 INFO [10/Apr/2024 00:29:43] Update /results/gatling//latest 2024-04-09 23:31:45,745 INFO ---------------------- 2024-04-09 23:31:45,745 INFO 2024-04-09 23:31:45,745 INFO ---------------------- 2024-04-09 23:31:45,745 INFO [10/Apr/2024 00:29:43] Update /results/gatling//latest.tar.gz 2024-04-09 23:31:45,745 INFO ---------------------- 2024-04-09 23:31:45,745 INFO ________________________________________________________________________________ [2024-04-09 23:31:45] authn-users-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-09 23:31:45,746 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt is small (0 lines), printing all lines: 2024-04-09 23:31:45,746 DEBUG ----- output ----- 2024-04-09 23:31:45,746 DEBUG 2024-04-09 23:31:45,746 INFO ________________________________________________________________________________ [2024-04-09 23:31:45] authn-users-100 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-09 23:31:45] authn-users-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-09 23:31:45] authn-users-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped