--Task-- name: authn-users-6500 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['authn-users-6000'] preceding_task: authn-users-6000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 6500, 'duration': 1800} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-15 22:40:15] authn-users-6500 pre : N/A ________________________________________________________________________________ [2024-04-15 22:40:15] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-15 22:40:15] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-6500/stdout.txt Pod log : /results/orders/authn-users-6500 2024-04-15 22:40:15,450 INFO 2024-04-15 22:40:15,451 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-15 22:40:15,451 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:15,757 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:15,757 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:15,757 DEBUG --- stdout --- 2024-04-15 22:40:15,757 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:15,757 DEBUG --- stderr --- 2024-04-15 22:40:15,757 DEBUG ________________________________________________________________________________ [2024-04-15 22:40:15] authn-users-6500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-15 22:40:15,758 INFO 2024-04-15 22:40:15,758 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-15 22:40:15,758 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:16,072 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:16,073 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:16,073 DEBUG --- stdout --- 2024-04-15 22:40:16,073 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:16,073 DEBUG --- stderr --- 2024-04-15 22:40:16,073 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-6500 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-15 22:40:16] authn-users-6500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-15 22:40:16,074 INFO 2024-04-15 22:40:16,074 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-15 22:40:16,074 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:16,403 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:16,403 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:16,403 DEBUG --- stdout --- 2024-04-15 22:40:16,403 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:16,403 DEBUG --- stderr --- 2024-04-15 22:40:16,404 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/order.json { "name": "authn-users-6500", "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-6500 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-6500", "environment": { "JAVA_OPTS": "-Dam_host=xlou.iam.xlou-cdm.engineeringpit.com -DamadminPassword=**** -DamadminUsername=amadmin -Dduration=1800 -Dnum_users=1000000 -DtestCase=am.AMAuthN -DtestMode=open -Dthroughput=6500 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-15 22:40:16] authn-users-6500 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-15 22:40:16,405 INFO 2024-04-15 22:40:16,405 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-15 22:40:16,405 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-15 22:40:16,700 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 22:40:16,700 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:16,700 DEBUG --- stdout --- 2024-04-15 22:40:16,700 DEBUG WAITING 2024-04-15 22:40:16,700 DEBUG --- stderr --- 2024-04-15 22:40:16,701 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 26 0 --:--:-- --:--:-- --:--:-- 26 ________________________________________________________________________________ [2024-04-15 22:40:16] authn-users-6500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-15 22:40:16,701 INFO 2024-04-15 22:40:16,701 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-15 22:40:16,701 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:17,035 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:17,036 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:17,036 DEBUG --- stdout --- 2024-04-15 22:40:17,036 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:17,036 DEBUG --- stderr --- 2024-04-15 22:40:17,036 DEBUG 2024-04-15 22:40:17,036 INFO 2024-04-15 22:40:17,036 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-6500/order.json overseer-0-5584f6c4db-bcn5q:/results/orders/order.json 2024-04-15 22:40:17,036 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:40:18,445 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:18,445 DEBUG --- stdout --- 2024-04-15 22:40:18,445 DEBUG 2024-04-15 22:40:18,445 DEBUG --- stderr --- 2024-04-15 22:40:18,445 DEBUG Order has been successfully forwarded to overseer [2024-04-15 22:40:18] - INFO: Check if Gatling simulation started successfully 2024-04-15 22:40:18,446 INFO 2024-04-15 22:40:18,446 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-15 22:40:18,446 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:18,851 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:18,851 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:18,851 DEBUG --- stdout --- 2024-04-15 22:40:18,852 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:18,852 DEBUG --- stderr --- 2024-04-15 22:40:18,852 DEBUG 2024-04-15 22:40:18,852 INFO 2024-04-15 22:40:18,852 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- head -n 25 /results/orders/authn-users-6500/stdout.txt | grep "started..." 2024-04-15 22:40:18,852 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-15 22:40:19,692 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-15 22:40:25,492 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-15 22:40:31,262 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-15 22:40:37,093 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-15 22:40:37,093 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:37,093 DEBUG --- stdout --- 2024-04-15 22:40:37,093 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 23:40:21.772 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 23:40:28.122 [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:40:28.125 [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:40:30.705 [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-15 22:40:37,093 DEBUG --- stderr --- 2024-04-15 22:40:37,093 DEBUG ________________________________________________________________________________ [2024-04-15 22:40:37] authn-users-6500 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 36m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 5s, retry Order is running since 10s, retry Order is running since 15s, retry Order is running since 20s, retry Order is running since 26s, retry Order is running since 31s, retry Order is running since 36s, retry Order is running since 41s, retry Order is running since 47s, retry Order is running since 52s, retry Order is running since 57s, retry Order is running since 1m 02s, retry Order is running since 1m 07s, retry Order is running since 1m 13s, retry Order is running since 1m 18s, retry Order is running since 1m 23s, retry Order is running since 1m 28s, retry Order is running since 1m 34s, retry Order is running since 1m 39s, retry Order is running since 1m 44s, retry Order is running since 1m 49s, retry Order is running since 1m 55s, retry Order is running since 2m, retry Order is running since 2m 05s, retry Order is running since 2m 10s, retry Order is running since 2m 16s, retry Order is running since 2m 21s, retry Order is running since 2m 26s, retry Order is running since 2m 32s, retry Order is running since 2m 37s, retry Order is running since 2m 42s, retry Order is running since 2m 47s, retry Order is running since 2m 53s, retry Order is running since 2m 58s, retry Order is running since 3m 03s, retry Order is running since 3m 08s, retry Order is running since 3m 13s, retry Order is running since 3m 19s, retry Order is running since 3m 24s, retry Order is running since 3m 29s, retry Order is running since 3m 34s, retry Order is running since 3m 40s, retry Order is running since 3m 45s, retry Order is running since 3m 50s, retry Order is running since 3m 55s, retry Order is running since 4m 01s, retry Order is running since 4m 06s, retry Order is running since 4m 11s, retry Order is running since 4m 16s, retry Order is running since 4m 22s, retry Order is running since 4m 27s, retry Order is running since 4m 32s, retry Order is running since 4m 37s, retry Order is running since 4m 43s, retry Order is running since 4m 48s, retry Order is running since 4m 53s, retry Order is running since 4m 58s, retry Order is running since 5m 04s, retry Order is running since 5m 09s, retry Order is running since 5m 14s, retry PASS : Order stopped by itself after 5m 19s and before timeout (36m) ________________________________________________________________________________ [2024-04-15 22:45:56] authn-users-6500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-15 22:45:56,811 INFO 2024-04-15 22:45:56,812 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-15 22:45:56,812 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:45:57,165 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:45:57,165 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:45:57,165 DEBUG --- stdout --- 2024-04-15 22:45:57,166 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:45:57,166 DEBUG --- stderr --- 2024-04-15 22:45:57,166 DEBUG 2024-04-15 22:45:57,166 INFO 2024-04-15 22:45:57,166 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- rm -f /results/orders/order.json 2024-04-15 22:45:57,166 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:45:57,983 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:45:57,983 DEBUG --- stdout --- 2024-04-15 22:45:57,983 DEBUG 2024-04-15 22:45:57,983 DEBUG --- stderr --- 2024-04-15 22:45:57,983 DEBUG Order ran during 5m 39s ________________________________________________________________________________ [2024-04-15 22:45:57] authn-users-6500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-15 22:45:57] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/order.json 2024-04-15 22:45:57,984 INFO 2024-04-15 22:45:57,984 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-15 22:45:57,984 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:45:58,327 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:45:58,327 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:45:58,327 DEBUG --- stdout --- 2024-04-15 22:45:58,328 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:45:58,328 DEBUG --- stderr --- 2024-04-15 22:45:58,328 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 22:45:58,328 INFO 2024-04-15 22:45:58,329 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-15 22:45:58,329 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:45:58,675 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:45:58,675 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:45:58,675 DEBUG --- stdout --- 2024-04-15 22:45:58,675 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:45:58,675 DEBUG --- stderr --- 2024-04-15 22:45:58,675 DEBUG 2024-04-15 22:45:58,676 INFO 2024-04-15 22:45:58,676 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6500/rc.txt" ]; then echo "/results/orders/authn-users-6500/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-6500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6500/rc.txt file found" 2024-04-15 22:45:58,677 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:45:59,541 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 22:45:59,541 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:45:59,541 DEBUG --- stdout --- 2024-04-15 22:45:59,541 DEBUG /results/orders/authn-users-6500/rc.txt file found 2024-04-15 22:45:59,541 DEBUG --- stderr --- 2024-04-15 22:45:59,541 DEBUG 2024-04-15 22:45:59,542 INFO 2024-04-15 22:45:59,542 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-15 22:45:59,542 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:45:59,883 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:45:59,883 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:45:59,883 DEBUG --- stdout --- 2024-04-15 22:45:59,884 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:45:59,884 DEBUG --- stderr --- 2024-04-15 22:45:59,884 DEBUG 2024-04-15 22:45:59,884 INFO 2024-04-15 22:45:59,884 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6500/rc.txt 2024-04-15 22:46:00,721 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 22:46:00,722 DEBUG --- stdout --- 2024-04-15 22:46:00,722 DEBUG 4 /results/orders/authn-users-6500/rc.txt 2024-04-15 22:46:00,722 DEBUG --- stderr --- 2024-04-15 22:46:00,722 DEBUG 2024-04-15 22:46:00,722 INFO - Download file (size 4k) 2024-04-15 22:46:00,722 INFO 2024-04-15 22:46:00,722 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-15 22:46:00,722 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:01,035 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:01,035 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:01,035 DEBUG --- stdout --- 2024-04-15 22:46:01,035 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:01,036 DEBUG --- stderr --- 2024-04-15 22:46:01,036 DEBUG source : /results/orders/authn-users-6500/rc.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/rc.txt 2024-04-15 22:46:01,036 INFO 2024-04-15 22:46:01,036 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-15 22:46:01,036 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:01,417 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:01,417 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:01,417 DEBUG --- stdout --- 2024-04-15 22:46:01,417 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:01,417 DEBUG --- stderr --- 2024-04-15 22:46:01,417 DEBUG 2024-04-15 22:46:01,418 INFO 2024-04-15 22:46:01,418 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6500/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/rc.txt 2024-04-15 22:46:01,418 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 22:46:02,231 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:02,231 DEBUG --- stdout --- 2024-04-15 22:46:02,231 DEBUG tar: Removing leading `/' from member names 2024-04-15 22:46:02,231 DEBUG --- stderr --- 2024-04-15 22:46:02,232 DEBUG 2024-04-15 22:46:02,232 INFO 2024-04-15 22:46:02,232 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-15 22:46:02,232 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:02,571 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:02,571 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:02,571 DEBUG --- stdout --- 2024-04-15 22:46:02,571 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:02,571 DEBUG --- stderr --- 2024-04-15 22:46:02,571 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 22:46:02,572 INFO 2024-04-15 22:46:02,572 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-15 22:46:02,572 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:02,945 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:02,945 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:02,945 DEBUG --- stdout --- 2024-04-15 22:46:02,945 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:02,945 DEBUG --- stderr --- 2024-04-15 22:46:02,945 DEBUG 2024-04-15 22:46:02,946 INFO 2024-04-15 22:46:02,946 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6500/stderr.txt" ]; then echo "/results/orders/authn-users-6500/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-6500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6500/stderr.txt file found" 2024-04-15 22:46:02,946 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:46:03,790 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 22:46:03,790 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:03,790 DEBUG --- stdout --- 2024-04-15 22:46:03,790 DEBUG /results/orders/authn-users-6500/stderr.txt file found 2024-04-15 22:46:03,790 DEBUG --- stderr --- 2024-04-15 22:46:03,790 DEBUG 2024-04-15 22:46:03,791 INFO 2024-04-15 22:46:03,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-15 22:46:03,791 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:04,136 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:04,136 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:04,136 DEBUG --- stdout --- 2024-04-15 22:46:04,136 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:04,136 DEBUG --- stderr --- 2024-04-15 22:46:04,136 DEBUG 2024-04-15 22:46:04,136 INFO 2024-04-15 22:46:04,136 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6500/stderr.txt 2024-04-15 22:46:05,020 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 22:46:05,020 DEBUG --- stdout --- 2024-04-15 22:46:05,021 DEBUG 0 /results/orders/authn-users-6500/stderr.txt 2024-04-15 22:46:05,021 DEBUG --- stderr --- 2024-04-15 22:46:05,021 DEBUG 2024-04-15 22:46:05,021 INFO - Download file (size 0k) 2024-04-15 22:46:05,022 INFO 2024-04-15 22:46:05,022 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-15 22:46:05,022 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:05,376 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:05,377 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:05,377 DEBUG --- stdout --- 2024-04-15 22:46:05,377 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:05,377 DEBUG --- stderr --- 2024-04-15 22:46:05,377 DEBUG source : /results/orders/authn-users-6500/stderr.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/stderr.txt 2024-04-15 22:46:05,378 INFO 2024-04-15 22:46:05,378 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-15 22:46:05,378 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:05,717 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:05,717 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:05,717 DEBUG --- stdout --- 2024-04-15 22:46:05,717 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:05,717 DEBUG --- stderr --- 2024-04-15 22:46:05,717 DEBUG 2024-04-15 22:46:05,717 INFO 2024-04-15 22:46:05,717 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6500/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/stderr.txt 2024-04-15 22:46:05,717 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 22:46:06,489 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:06,489 DEBUG --- stdout --- 2024-04-15 22:46:06,489 DEBUG tar: Removing leading `/' from member names 2024-04-15 22:46:06,489 DEBUG --- stderr --- 2024-04-15 22:46:06,489 DEBUG 2024-04-15 22:46:06,489 INFO 2024-04-15 22:46:06,489 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-15 22:46:06,489 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:06,801 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:06,801 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:06,801 DEBUG --- stdout --- 2024-04-15 22:46:06,801 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:06,801 DEBUG --- stderr --- 2024-04-15 22:46:06,801 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 22:46:06,801 INFO 2024-04-15 22:46:06,801 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-15 22:46:06,801 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:07,109 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:07,110 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:07,110 DEBUG --- stdout --- 2024-04-15 22:46:07,110 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:07,110 DEBUG --- stderr --- 2024-04-15 22:46:07,110 DEBUG 2024-04-15 22:46:07,111 INFO 2024-04-15 22:46:07,111 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6500/stdout.txt" ]; then echo "/results/orders/authn-users-6500/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-6500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6500/stdout.txt file found" 2024-04-15 22:46:07,111 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:46:07,962 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 22:46:07,963 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:07,963 DEBUG --- stdout --- 2024-04-15 22:46:07,963 DEBUG /results/orders/authn-users-6500/stdout.txt file found 2024-04-15 22:46:07,963 DEBUG --- stderr --- 2024-04-15 22:46:07,963 DEBUG 2024-04-15 22:46:07,963 INFO 2024-04-15 22:46:07,963 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-15 22:46:07,963 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:08,286 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:08,287 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:08,287 DEBUG --- stdout --- 2024-04-15 22:46:08,287 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:08,287 DEBUG --- stderr --- 2024-04-15 22:46:08,287 DEBUG 2024-04-15 22:46:08,287 INFO 2024-04-15 22:46:08,287 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6500/stdout.txt 2024-04-15 22:46:09,117 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 22:46:09,118 DEBUG --- stdout --- 2024-04-15 22:46:09,118 DEBUG 2796 /results/orders/authn-users-6500/stdout.txt 2024-04-15 22:46:09,118 DEBUG --- stderr --- 2024-04-15 22:46:09,118 DEBUG 2024-04-15 22:46:09,118 INFO - Download file (size 2796k) 2024-04-15 22:46:09,118 INFO 2024-04-15 22:46:09,118 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-15 22:46:09,118 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:09,443 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:09,443 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:09,443 DEBUG --- stdout --- 2024-04-15 22:46:09,444 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:09,444 DEBUG --- stderr --- 2024-04-15 22:46:09,444 DEBUG source : /results/orders/authn-users-6500/stdout.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/stdout.txt 2024-04-15 22:46:09,444 INFO 2024-04-15 22:46:09,444 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-15 22:46:09,444 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:09,830 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:09,830 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:09,830 DEBUG --- stdout --- 2024-04-15 22:46:09,830 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:09,830 DEBUG --- stderr --- 2024-04-15 22:46:09,831 DEBUG 2024-04-15 22:46:09,831 INFO 2024-04-15 22:46:09,831 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6500/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/stdout.txt 2024-04-15 22:46:09,831 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 22:46:11,116 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:11,116 DEBUG --- stdout --- 2024-04-15 22:46:11,116 DEBUG tar: Removing leading `/' from member names 2024-04-15 22:46:11,116 DEBUG --- stderr --- 2024-04-15 22:46:11,116 DEBUG ________________________________________________________________________________ [2024-04-15 22:46:11] authn-users-6500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-15 22:46:11,117 INFO 2024-04-15 22:46:11,117 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-15 22:46:11,117 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:11,432 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:11,432 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:11,432 DEBUG --- stdout --- 2024-04-15 22:46:11,432 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:11,433 DEBUG --- stderr --- 2024-04-15 22:46:11,433 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 22:46:11,433 INFO 2024-04-15 22:46:11,433 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-15 22:46:11,433 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:11,770 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:11,771 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:11,771 DEBUG --- stdout --- 2024-04-15 22:46:11,771 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:11,771 DEBUG --- stderr --- 2024-04-15 22:46:11,771 DEBUG 2024-04-15 22:46:11,771 INFO 2024-04-15 22:46:11,771 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6500/cmd.txt" ]; then echo "/results/orders/authn-users-6500/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-6500/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6500/cmd.txt file found" 2024-04-15 22:46:11,771 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:46:12,619 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 22:46:12,619 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:12,619 DEBUG --- stdout --- 2024-04-15 22:46:12,619 DEBUG /results/orders/authn-users-6500/cmd.txt file found 2024-04-15 22:46:12,619 DEBUG --- stderr --- 2024-04-15 22:46:12,619 DEBUG 2024-04-15 22:46:12,620 INFO 2024-04-15 22:46:12,620 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-15 22:46:12,620 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:12,923 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:12,923 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:12,923 DEBUG --- stdout --- 2024-04-15 22:46:12,923 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:12,923 DEBUG --- stderr --- 2024-04-15 22:46:12,923 DEBUG 2024-04-15 22:46:12,923 INFO 2024-04-15 22:46:12,924 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6500/cmd.txt 2024-04-15 22:46:13,764 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 22:46:13,765 DEBUG --- stdout --- 2024-04-15 22:46:13,765 DEBUG 4 /results/orders/authn-users-6500/cmd.txt 2024-04-15 22:46:13,765 DEBUG --- stderr --- 2024-04-15 22:46:13,765 DEBUG 2024-04-15 22:46:13,765 INFO - Download file (size 4k) 2024-04-15 22:46:13,765 INFO 2024-04-15 22:46:13,765 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-15 22:46:13,765 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:14,117 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:14,117 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:14,117 DEBUG --- stdout --- 2024-04-15 22:46:14,117 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:14,117 DEBUG --- stderr --- 2024-04-15 22:46:14,117 DEBUG source : /results/orders/authn-users-6500/cmd.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/cmd.txt 2024-04-15 22:46:14,118 INFO 2024-04-15 22:46:14,118 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-15 22:46:14,118 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:46:14,447 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:46:14,447 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:14,447 DEBUG --- stdout --- 2024-04-15 22:46:14,447 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:46:14,447 DEBUG --- stderr --- 2024-04-15 22:46:14,447 DEBUG 2024-04-15 22:46:14,448 INFO 2024-04-15 22:46:14,448 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6500/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/cmd.txt 2024-04-15 22:46:14,448 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 22:46:15,251 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:46:15,252 DEBUG --- stdout --- 2024-04-15 22:46:15,252 DEBUG tar: Removing leading `/' from member names 2024-04-15 22:46:15,252 DEBUG --- stderr --- 2024-04-15 22:46:15,252 DEBUG 2024-04-15 22:46:15,252 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/cmd.txt is small (1 lines), printing all lines: 2024-04-15 22:46:15,252 DEBUG ----- output ----- 2024-04-15 22:46:15,252 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-6500 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-15 22:46:15,252 INFO ________________________________________________________________________________ [2024-04-15 22:46:15] authn-users-6500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-15 22:46:15,253 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/rc.txt is small (1 lines), printing all lines: 2024-04-15 22:46:15,253 DEBUG ----- output ----- 2024-04-15 22:46:15,253 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-15 22:46:15,253 INFO ________________________________________________________________________________ [2024-04-15 22:46:15] authn-users-6500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-15 22:46:15,264 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-6500/stdout.txt is 44385 lines): 2024-04-15 22:46:15,264 DEBUG ----- output ----- 2024-04-15 22:46:15,265 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-15 22:46:15,265 INFO 23:40:21.772 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-15 22:46:15,265 INFO 23:40:28.122 [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-15 22:46:15,265 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-15 22:46:15,265 INFO ^ 2024-04-15 22:46:15,265 INFO 23:40:28.125 [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-15 22:46:15,265 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-15 22:46:15,265 INFO ^ 2024-04-15 22:46:15,265 INFO 23:40:30.705 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-15 22:46:15,265 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-15 22:46:15,265 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-15 22:46:15,265 INFO 2024-04-15 22:46:15,265 INFO ================================================================================ 2024-04-15 22:46:15,265 INFO 2024-04-15 23:40:40 5s elapsed 2024-04-15 22:46:15,265 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,265 INFO > Global (OK=2712 KO=0 ) 2024-04-15 22:46:15,265 INFO > userRestLogin (OK=2712 KO=0 ) 2024-04-15 22:46:15,265 INFO 2024-04-15 22:46:15,265 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,265 INFO [- ] 0% 2024-04-15 22:46:15,265 INFO waiting: 11797304 / active: 13 / done: 2712 2024-04-15 22:46:15,265 INFO ================================================================================ 2024-04-15 22:46:15,265 INFO 2024-04-15 22:46:15,265 INFO 2024-04-15 22:46:15,266 INFO ================================================================================ 2024-04-15 22:46:15,266 INFO 2024-04-15 23:40:45 10s elapsed 2024-04-15 22:46:15,266 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,266 INFO > Global (OK=10837 KO=0 ) 2024-04-15 22:46:15,266 INFO > userRestLogin (OK=10837 KO=0 ) 2024-04-15 22:46:15,266 INFO 2024-04-15 22:46:15,266 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,266 INFO [- ] 0% 2024-04-15 22:46:15,266 INFO waiting: 11789164 / active: 28 / done: 10837 2024-04-15 22:46:15,266 INFO ================================================================================ 2024-04-15 22:46:15,266 INFO 2024-04-15 22:46:15,266 INFO 2024-04-15 22:46:15,266 INFO ================================================================================ 2024-04-15 22:46:15,266 INFO 2024-04-15 23:40:50 15s elapsed 2024-04-15 22:46:15,266 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,266 INFO > Global (OK=24382 KO=0 ) 2024-04-15 22:46:15,266 INFO > userRestLogin (OK=24382 KO=0 ) 2024-04-15 22:46:15,266 INFO 2024-04-15 22:46:15,266 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,266 INFO [- ] 0% 2024-04-15 22:46:15,266 INFO waiting: 11775606 / active: 41 / done: 24382 2024-04-15 22:46:15,266 INFO ================================================================================ 2024-04-15 22:46:15,266 INFO 2024-04-15 22:46:15,267 INFO 2024-04-15 22:46:15,267 INFO ================================================================================ 2024-04-15 22:46:15,267 INFO 2024-04-15 23:40:55 20s elapsed 2024-04-15 22:46:15,267 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,267 INFO > Global (OK=43337 KO=0 ) 2024-04-15 22:46:15,267 INFO > userRestLogin (OK=43337 KO=0 ) 2024-04-15 22:46:15,267 INFO 2024-04-15 22:46:15,267 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,267 INFO [- ] 0% 2024-04-15 22:46:15,267 INFO waiting: 11756633 / active: 60 / done: 43336 2024-04-15 22:46:15,267 INFO ================================================================================ 2024-04-15 22:46:15,267 INFO 2024-04-15 22:46:15,267 INFO 2024-04-15 22:46:15,267 INFO ================================================================================ 2024-04-15 22:46:15,267 INFO 2024-04-15 23:41:00 25s elapsed 2024-04-15 22:46:15,267 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,267 INFO > Global (OK=67700 KO=0 ) 2024-04-15 22:46:15,267 INFO > userRestLogin (OK=67700 KO=0 ) 2024-04-15 22:46:15,267 INFO 2024-04-15 22:46:15,267 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,267 INFO [- ] 0% 2024-04-15 22:46:15,267 INFO waiting: 11732243 / active: 87 / done: 67699 2024-04-15 22:46:15,267 INFO ================================================================================ 2024-04-15 22:46:15,267 INFO 2024-04-15 22:46:15,267 INFO 2024-04-15 22:46:15,267 INFO ================================================================================ 2024-04-15 22:46:15,267 INFO 2024-04-15 23:41:05 30s elapsed 2024-04-15 22:46:15,268 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,268 INFO > Global (OK=97153 KO=0 ) 2024-04-15 22:46:15,268 INFO > userRestLogin (OK=97153 KO=0 ) 2024-04-15 22:46:15,268 INFO 2024-04-15 22:46:15,268 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,268 INFO [- ] 0% 2024-04-15 22:46:15,268 INFO waiting: 11702446 / active: 430 / done: 97153 2024-04-15 22:46:15,268 INFO ================================================================================ 2024-04-15 22:46:15,268 INFO 2024-04-15 22:46:15,268 INFO 2024-04-15 22:46:15,268 INFO ================================================================================ 2024-04-15 22:46:15,268 INFO 2024-04-15 23:41:10 35s elapsed 2024-04-15 22:46:15,268 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,268 INFO > Global (OK=129102 KO=0 ) 2024-04-15 22:46:15,268 INFO > userRestLogin (OK=129102 KO=0 ) 2024-04-15 22:46:15,268 INFO 2024-04-15 22:46:15,268 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,268 INFO [- ] 1% 2024-04-15 22:46:15,268 INFO waiting: 11669931 / active: 997 / done: 129101 2024-04-15 22:46:15,268 INFO ================================================================================ 2024-04-15 22:46:15,268 INFO 2024-04-15 22:46:15,268 INFO 2024-04-15 22:46:15,268 INFO ================================================================================ 2024-04-15 22:46:15,268 INFO 2024-04-15 23:41:15 40s elapsed 2024-04-15 22:46:15,268 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,268 INFO > Global (OK=161476 KO=0 ) 2024-04-15 22:46:15,268 INFO [...] 2024-04-15 22:46:15,288 INFO .47.242:443 after 60000 ms 2024-04-15 22:46:15,288 INFO > Request timeout after 60000 ms 193 ( 9.75%) 2024-04-15 22:46:15,288 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 5 ( 0.25%) 2024-04-15 22:46:15,288 INFO ), but actually found 502 2024-04-15 22:46:15,289 INFO 2024-04-15 22:46:15,289 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,289 INFO [#######--- ] 10% 2024-04-15 22:46:15,289 INFO waiting: 10189296 / active: 397903 / done: 1212830 2024-04-15 22:46:15,289 INFO ================================================================================ 2024-04-15 22:46:15,289 INFO 2024-04-15 22:46:15,289 INFO 2024-04-15 22:46:15,289 INFO ================================================================================ 2024-04-15 22:46:15,289 INFO 2024-04-15 23:45:35 300s elapsed 2024-04-15 22:46:15,289 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,289 INFO > Global (OK=1227421 KO=1980 ) 2024-04-15 22:46:15,289 INFO > userRestLogin (OK=1227421 KO=1980 ) 2024-04-15 22:46:15,289 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 22:46:15,289 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 1782 (90.00%) 2024-04-15 22:46:15,289 INFO .47.242:443 after 60000 ms 2024-04-15 22:46:15,289 INFO > Request timeout after 60000 ms 193 ( 9.75%) 2024-04-15 22:46:15,289 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 5 ( 0.25%) 2024-04-15 22:46:15,289 INFO ), but actually found 502 2024-04-15 22:46:15,289 INFO 2024-04-15 22:46:15,289 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,289 INFO [#######--- ] 10% 2024-04-15 22:46:15,289 INFO waiting: 10167760 / active: 402868 / done: 1229401 2024-04-15 22:46:15,289 INFO ================================================================================ 2024-04-15 22:46:15,289 INFO 2024-04-15 22:46:15,289 INFO 2024-04-15 22:46:15,289 INFO ================================================================================ 2024-04-15 22:46:15,289 INFO 2024-04-15 23:45:40 305s elapsed 2024-04-15 22:46:15,289 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,289 INFO > Global (OK=1240120 KO=1980 ) 2024-04-15 22:46:15,289 INFO > userRestLogin (OK=1240120 KO=1980 ) 2024-04-15 22:46:15,290 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 22:46:15,290 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 1782 (90.00%) 2024-04-15 22:46:15,290 INFO .47.242:443 after 60000 ms 2024-04-15 22:46:15,290 INFO > Request timeout after 60000 ms 193 ( 9.75%) 2024-04-15 22:46:15,290 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 5 ( 0.25%) 2024-04-15 22:46:15,290 INFO ), but actually found 502 2024-04-15 22:46:15,290 INFO 2024-04-15 22:46:15,290 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,290 INFO [#######--- ] 10% 2024-04-15 22:46:15,290 INFO waiting: 10162937 / active: 395085 / done: 1242007 2024-04-15 22:46:15,290 INFO ================================================================================ 2024-04-15 22:46:15,290 INFO 2024-04-15 22:46:15,290 INFO 2024-04-15 22:46:15,290 INFO ================================================================================ 2024-04-15 22:46:15,290 INFO 2024-04-15 23:45:45 310s elapsed 2024-04-15 22:46:15,290 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,290 INFO > Global (OK=1256609 KO=1980 ) 2024-04-15 22:46:15,290 INFO > userRestLogin (OK=1256609 KO=1980 ) 2024-04-15 22:46:15,290 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 22:46:15,290 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 1782 (90.00%) 2024-04-15 22:46:15,290 INFO .47.242:443 after 60000 ms 2024-04-15 22:46:15,290 INFO > Request timeout after 60000 ms 193 ( 9.75%) 2024-04-15 22:46:15,290 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 5 ( 0.25%) 2024-04-15 22:46:15,290 INFO ), but actually found 502 2024-04-15 22:46:15,290 INFO 2024-04-15 22:46:15,290 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,290 INFO [#######--- ] 10% 2024-04-15 22:46:15,290 INFO waiting: 10142251 / active: 399220 / done: 1258558 2024-04-15 22:46:15,290 INFO ================================================================================ 2024-04-15 22:46:15,290 INFO 2024-04-15 22:46:15,291 INFO 2024-04-15 22:46:15,291 INFO ================================================================================ 2024-04-15 22:46:15,291 INFO 2024-04-15 23:45:50 315s elapsed 2024-04-15 22:46:15,291 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:46:15,291 INFO > Global (OK=1273588 KO=1980 ) 2024-04-15 22:46:15,291 INFO > userRestLogin (OK=1273588 KO=1980 ) 2024-04-15 22:46:15,291 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 22:46:15,291 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 1782 (90.00%) 2024-04-15 22:46:15,291 INFO .47.242:443 after 60000 ms 2024-04-15 22:46:15,291 INFO > Request timeout after 60000 ms 193 ( 9.75%) 2024-04-15 22:46:15,291 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 5 ( 0.25%) 2024-04-15 22:46:15,291 INFO ), but actually found 502 2024-04-15 22:46:15,291 INFO 2024-04-15 22:46:15,291 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:46:15,291 INFO [#######--- ] 10% 2024-04-15 22:46:15,291 INFO waiting: 10127703 / active: 396758 / done: 1275568 2024-04-15 22:46:15,291 INFO ================================================================================ 2024-04-15 22:46:15,291 INFO 2024-04-15 22:46:15,291 INFO 2024-04-15 22:46:15,291 INFO ---------------------- 2024-04-15 22:46:15,291 INFO [15/Apr/2024 23:45:53] Create tar file authn-users-6500.tar.gz 2024-04-15 22:46:15,291 INFO ---------------------- 2024-04-15 22:46:15,291 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-15 22:46:15,291 INFO RESULT_FOLDER=authn-users-6500 2024-04-15 22:46:15,291 INFO SIMULATION_PATH=authn-users-6500/loadtest-20240415224035032 2024-04-15 22:46:15,291 INFO PWD=/results/gatling/authn-users-6500/loadtest-20240415224035032 2024-04-15 22:46:15,291 INFO [cmd] tar -czvf /results/gatling//authn-users-6500.tar.gz --exclude=simulation.log * 2024-04-15 22:46:15,291 INFO /results/gatling 2024-04-15 22:46:15,291 INFO 2024-04-15 22:46:15,291 INFO ---------------------- 2024-04-15 22:46:15,291 INFO [15/Apr/2024 23:45:53] Update /results/gatling//latest 2024-04-15 22:46:15,291 INFO ---------------------- 2024-04-15 22:46:15,291 INFO 2024-04-15 22:46:15,292 INFO ---------------------- 2024-04-15 22:46:15,292 INFO [15/Apr/2024 23:45:53] Update /results/gatling//latest.tar.gz 2024-04-15 22:46:15,292 INFO ---------------------- 2024-04-15 22:46:15,292 INFO ________________________________________________________________________________ [2024-04-15 22:46:15] authn-users-6500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-15 22:46:15,292 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-6500/stderr.txt is small (0 lines), printing all lines: 2024-04-15 22:46:15,292 DEBUG ----- output ----- 2024-04-15 22:46:15,292 DEBUG 2024-04-15 22:46:15,292 INFO ________________________________________________________________________________ [2024-04-15 22:46:15] authn-users-6500 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-15 22:46:15] authn-users-6500 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-15 22:46:15] authn-users-6500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped