--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': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-14 23:52:18] authn-users-6500 pre : N/A ________________________________________________________________________________ [2024-04-14 23:52:18] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-14 23:52:18] - 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-14 23:52:18,472 INFO 2024-04-14 23:52:18,473 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 23:52:18,473 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:18,829 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:18,829 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:18,829 DEBUG --- stdout --- 2024-04-14 23:52:18,829 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:18,829 DEBUG --- stderr --- 2024-04-14 23:52:18,829 DEBUG ________________________________________________________________________________ [2024-04-14 23:52:18] authn-users-6500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-14 23:52:18,829 INFO 2024-04-14 23:52:18,830 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 23:52:18,830 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:19,184 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:19,184 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:19,184 DEBUG --- stdout --- 2024-04-14 23:52:19,184 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:19,185 DEBUG --- stderr --- 2024-04-14 23:52:19,185 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-14 23:52:19] authn-users-6500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-14 23:52:19,185 INFO 2024-04-14 23:52:19,185 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 23:52:19,185 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:19,556 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:19,556 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:19,556 DEBUG --- stdout --- 2024-04-14 23:52:19,556 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:19,557 DEBUG --- stderr --- 2024-04-14 23:52:19,557 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_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=3600 -Dnum_users=1000000 -DtestCase=am.AMAuthN -DtestMode=open -Dthroughput=6500 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-14 23:52:19] 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-14 23:52:19,558 INFO 2024-04-14 23:52:19,558 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-14 23:52:19,558 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 23:52:19,801 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 23:52:19,801 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:19,801 DEBUG --- stdout --- 2024-04-14 23:52:19,801 DEBUG WAITING 2024-04-14 23:52:19,801 DEBUG --- stderr --- 2024-04-14 23:52:19,801 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-14 23:52:19] authn-users-6500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-14 23:52:19,801 INFO 2024-04-14 23:52:19,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-14 23:52:19,802 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:20,133 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:20,133 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:20,133 DEBUG --- stdout --- 2024-04-14 23:52:20,133 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:20,133 DEBUG --- stderr --- 2024-04-14 23:52:20,133 DEBUG 2024-04-14 23:52:20,134 INFO 2024-04-14 23:52:20,134 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/07_authn-users-6500/order.json overseer-0-5584f6c4db-bcn5q:/results/orders/order.json 2024-04-14 23:52:20,134 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 23:52:21,534 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:21,535 DEBUG --- stdout --- 2024-04-14 23:52:21,535 DEBUG 2024-04-14 23:52:21,535 DEBUG --- stderr --- 2024-04-14 23:52:21,535 DEBUG Order has been successfully forwarded to overseer [2024-04-14 23:52:21] - INFO: Check if Gatling simulation started successfully 2024-04-14 23:52:21,535 INFO 2024-04-14 23:52:21,535 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 23:52:21,535 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:21,859 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:21,861 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:21,861 DEBUG --- stdout --- 2024-04-14 23:52:21,861 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:21,861 DEBUG --- stderr --- 2024-04-14 23:52:21,861 DEBUG 2024-04-14 23:52:21,862 INFO 2024-04-14 23:52:21,862 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-14 23:52:21,862 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 23:52:22,645 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-14 23:52:28,403 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-14 23:52:34,175 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-14 23:52:34,176 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:34,176 DEBUG --- stdout --- 2024-04-14 23:52:34,176 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-04-14 23:52:34,176 DEBUG --- stderr --- 2024-04-14 23:52:34,176 DEBUG ________________________________________________________________________________ [2024-04-14 23:52:34] 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 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry PASS : Order stopped by itself after 10m 01s and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-15 00:02:35] authn-users-6500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-15 00:02:35,885 INFO 2024-04-15 00:02:35,886 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 00:02:35,886 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:36,229 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:36,229 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:36,229 DEBUG --- stdout --- 2024-04-15 00:02:36,229 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:36,229 DEBUG --- stderr --- 2024-04-15 00:02:36,229 DEBUG 2024-04-15 00:02:36,229 INFO 2024-04-15 00:02:36,229 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 00:02:36,230 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 00:02:37,059 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:37,059 DEBUG --- stdout --- 2024-04-15 00:02:37,059 DEBUG 2024-04-15 00:02:37,059 DEBUG --- stderr --- 2024-04-15 00:02:37,060 DEBUG Order ran during 10m 15s ________________________________________________________________________________ [2024-04-15 00:02:37] authn-users-6500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-15 00:02:37] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-6500/order.json 2024-04-15 00:02:37,060 INFO 2024-04-15 00:02:37,060 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-15 00:02:37,060 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:37,367 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:37,367 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:37,367 DEBUG --- stdout --- 2024-04-15 00:02:37,367 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:37,367 DEBUG --- stderr --- 2024-04-15 00:02:37,367 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 00:02:37,367 INFO 2024-04-15 00:02:37,367 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 00:02:37,367 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:37,675 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:37,675 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:37,675 DEBUG --- stdout --- 2024-04-15 00:02:37,675 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:37,675 DEBUG --- stderr --- 2024-04-15 00:02:37,675 DEBUG 2024-04-15 00:02:37,676 INFO 2024-04-15 00:02:37,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 00:02:37,676 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 00:02:38,494 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 00:02:38,494 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:38,494 DEBUG --- stdout --- 2024-04-15 00:02:38,494 DEBUG /results/orders/authn-users-6500/rc.txt file found 2024-04-15 00:02:38,494 DEBUG --- stderr --- 2024-04-15 00:02:38,494 DEBUG 2024-04-15 00:02:38,495 INFO 2024-04-15 00:02:38,495 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 00:02:38,495 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:38,832 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:38,832 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:38,833 DEBUG --- stdout --- 2024-04-15 00:02:38,833 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:38,833 DEBUG --- stderr --- 2024-04-15 00:02:38,833 DEBUG 2024-04-15 00:02:38,833 INFO 2024-04-15 00:02:38,834 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 00:02:39,731 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 00:02:39,732 DEBUG --- stdout --- 2024-04-15 00:02:39,732 DEBUG 4 /results/orders/authn-users-6500/rc.txt 2024-04-15 00:02:39,732 DEBUG --- stderr --- 2024-04-15 00:02:39,732 DEBUG 2024-04-15 00:02:39,732 INFO - Download file (size 4k) 2024-04-15 00:02:39,733 INFO 2024-04-15 00:02:39,733 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 00:02:39,733 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:40,061 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:40,061 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:40,061 DEBUG --- stdout --- 2024-04-15 00:02:40,061 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:40,061 DEBUG --- stderr --- 2024-04-15 00:02:40,061 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/07_authn-users-6500/rc.txt 2024-04-15 00:02:40,062 INFO 2024-04-15 00:02:40,062 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 00:02:40,062 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:40,435 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:40,436 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:40,437 DEBUG --- stdout --- 2024-04-15 00:02:40,437 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:40,437 DEBUG --- stderr --- 2024-04-15 00:02:40,437 DEBUG 2024-04-15 00:02:40,438 INFO 2024-04-15 00:02:40,438 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/07_authn-users-6500/rc.txt 2024-04-15 00:02:40,438 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 00:02:41,251 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:41,251 DEBUG --- stdout --- 2024-04-15 00:02:41,251 DEBUG tar: Removing leading `/' from member names 2024-04-15 00:02:41,251 DEBUG --- stderr --- 2024-04-15 00:02:41,251 DEBUG 2024-04-15 00:02:41,251 INFO 2024-04-15 00:02:41,251 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 00:02:41,252 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:41,557 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:41,557 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:41,557 DEBUG --- stdout --- 2024-04-15 00:02:41,557 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:41,557 DEBUG --- stderr --- 2024-04-15 00:02:41,557 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 00:02:41,557 INFO 2024-04-15 00:02:41,557 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 00:02:41,557 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:41,864 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:41,865 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:41,865 DEBUG --- stdout --- 2024-04-15 00:02:41,865 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:41,865 DEBUG --- stderr --- 2024-04-15 00:02:41,865 DEBUG 2024-04-15 00:02:41,865 INFO 2024-04-15 00:02:41,865 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 00:02:41,865 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 00:02:42,686 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 00:02:42,686 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:42,686 DEBUG --- stdout --- 2024-04-15 00:02:42,686 DEBUG /results/orders/authn-users-6500/stderr.txt file found 2024-04-15 00:02:42,686 DEBUG --- stderr --- 2024-04-15 00:02:42,686 DEBUG 2024-04-15 00:02:42,686 INFO 2024-04-15 00:02:42,686 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 00:02:42,687 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:42,993 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:42,993 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:42,993 DEBUG --- stdout --- 2024-04-15 00:02:42,993 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:42,993 DEBUG --- stderr --- 2024-04-15 00:02:42,993 DEBUG 2024-04-15 00:02:42,993 INFO 2024-04-15 00:02:42,994 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 00:02:43,841 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 00:02:43,842 DEBUG --- stdout --- 2024-04-15 00:02:43,842 DEBUG 0 /results/orders/authn-users-6500/stderr.txt 2024-04-15 00:02:43,842 DEBUG --- stderr --- 2024-04-15 00:02:43,842 DEBUG 2024-04-15 00:02:43,842 INFO - Download file (size 0k) 2024-04-15 00:02:43,842 INFO 2024-04-15 00:02:43,842 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 00:02:43,842 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:44,161 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:44,162 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:44,162 DEBUG --- stdout --- 2024-04-15 00:02:44,162 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:44,162 DEBUG --- stderr --- 2024-04-15 00:02:44,162 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/07_authn-users-6500/stderr.txt 2024-04-15 00:02:44,162 INFO 2024-04-15 00:02:44,162 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 00:02:44,162 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:44,471 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:44,472 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:44,472 DEBUG --- stdout --- 2024-04-15 00:02:44,472 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:44,472 DEBUG --- stderr --- 2024-04-15 00:02:44,472 DEBUG 2024-04-15 00:02:44,472 INFO 2024-04-15 00:02:44,472 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/07_authn-users-6500/stderr.txt 2024-04-15 00:02:44,472 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 00:02:45,285 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:45,285 DEBUG --- stdout --- 2024-04-15 00:02:45,285 DEBUG tar: Removing leading `/' from member names 2024-04-15 00:02:45,285 DEBUG --- stderr --- 2024-04-15 00:02:45,285 DEBUG 2024-04-15 00:02:45,285 INFO 2024-04-15 00:02:45,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-15 00:02:45,286 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:45,656 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:45,656 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:45,656 DEBUG --- stdout --- 2024-04-15 00:02:45,656 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:45,656 DEBUG --- stderr --- 2024-04-15 00:02:45,656 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 00:02:45,657 INFO 2024-04-15 00:02:45,657 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 00:02:45,657 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:46,034 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:46,034 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:46,034 DEBUG --- stdout --- 2024-04-15 00:02:46,034 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:46,034 DEBUG --- stderr --- 2024-04-15 00:02:46,034 DEBUG 2024-04-15 00:02:46,035 INFO 2024-04-15 00:02:46,035 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 00:02:46,035 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 00:02:46,897 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 00:02:46,897 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:46,897 DEBUG --- stdout --- 2024-04-15 00:02:46,897 DEBUG /results/orders/authn-users-6500/stdout.txt file found 2024-04-15 00:02:46,897 DEBUG --- stderr --- 2024-04-15 00:02:46,897 DEBUG 2024-04-15 00:02:46,898 INFO 2024-04-15 00:02:46,898 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 00:02:46,898 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:47,217 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:47,217 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:47,218 DEBUG --- stdout --- 2024-04-15 00:02:47,218 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:47,218 DEBUG --- stderr --- 2024-04-15 00:02:47,218 DEBUG 2024-04-15 00:02:47,218 INFO 2024-04-15 00:02:47,218 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 00:02:48,056 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 00:02:48,056 DEBUG --- stdout --- 2024-04-15 00:02:48,056 DEBUG 324 /results/orders/authn-users-6500/stdout.txt 2024-04-15 00:02:48,056 DEBUG --- stderr --- 2024-04-15 00:02:48,057 DEBUG 2024-04-15 00:02:48,057 INFO - Download file (size 324k) 2024-04-15 00:02:48,057 INFO 2024-04-15 00:02:48,057 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 00:02:48,057 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:48,387 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:48,387 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:48,387 DEBUG --- stdout --- 2024-04-15 00:02:48,387 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:48,387 DEBUG --- stderr --- 2024-04-15 00:02:48,388 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/07_authn-users-6500/stdout.txt 2024-04-15 00:02:48,388 INFO 2024-04-15 00:02:48,388 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 00:02:48,388 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:48,712 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:48,712 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:48,712 DEBUG --- stdout --- 2024-04-15 00:02:48,712 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:48,712 DEBUG --- stderr --- 2024-04-15 00:02:48,712 DEBUG 2024-04-15 00:02:48,713 INFO 2024-04-15 00:02:48,713 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/07_authn-users-6500/stdout.txt 2024-04-15 00:02:48,713 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 00:02:49,751 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:49,751 DEBUG --- stdout --- 2024-04-15 00:02:49,751 DEBUG tar: Removing leading `/' from member names 2024-04-15 00:02:49,751 DEBUG --- stderr --- 2024-04-15 00:02:49,751 DEBUG ________________________________________________________________________________ [2024-04-15 00:02:49] authn-users-6500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-15 00:02:49,752 INFO 2024-04-15 00:02:49,752 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-15 00:02:49,752 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:50,083 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:50,084 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:50,084 DEBUG --- stdout --- 2024-04-15 00:02:50,084 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:50,084 DEBUG --- stderr --- 2024-04-15 00:02:50,084 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 00:02:50,084 INFO 2024-04-15 00:02:50,084 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 00:02:50,084 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:50,423 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:50,423 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:50,423 DEBUG --- stdout --- 2024-04-15 00:02:50,423 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:50,423 DEBUG --- stderr --- 2024-04-15 00:02:50,424 DEBUG 2024-04-15 00:02:50,424 INFO 2024-04-15 00:02:50,424 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 00:02:50,424 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 00:02:51,310 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 00:02:51,312 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:51,312 DEBUG --- stdout --- 2024-04-15 00:02:51,313 DEBUG /results/orders/authn-users-6500/cmd.txt file found 2024-04-15 00:02:51,313 DEBUG --- stderr --- 2024-04-15 00:02:51,313 DEBUG 2024-04-15 00:02:51,313 INFO 2024-04-15 00:02:51,313 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 00:02:51,313 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:51,691 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:51,691 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:51,691 DEBUG --- stdout --- 2024-04-15 00:02:51,691 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:51,691 DEBUG --- stderr --- 2024-04-15 00:02:51,691 DEBUG 2024-04-15 00:02:51,691 INFO 2024-04-15 00:02:51,692 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 00:02:52,599 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 00:02:52,599 DEBUG --- stdout --- 2024-04-15 00:02:52,599 DEBUG 4 /results/orders/authn-users-6500/cmd.txt 2024-04-15 00:02:52,599 DEBUG --- stderr --- 2024-04-15 00:02:52,599 DEBUG 2024-04-15 00:02:52,599 INFO - Download file (size 4k) 2024-04-15 00:02:52,600 INFO 2024-04-15 00:02:52,600 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 00:02:52,600 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:52,905 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:52,905 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:52,905 DEBUG --- stdout --- 2024-04-15 00:02:52,905 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:52,905 DEBUG --- stderr --- 2024-04-15 00:02:52,905 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/07_authn-users-6500/cmd.txt 2024-04-15 00:02:52,906 INFO 2024-04-15 00:02:52,906 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 00:02:52,906 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 00:02:53,217 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 00:02:53,217 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:53,217 DEBUG --- stdout --- 2024-04-15 00:02:53,217 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 00:02:53,217 DEBUG --- stderr --- 2024-04-15 00:02:53,217 DEBUG 2024-04-15 00:02:53,218 INFO 2024-04-15 00:02:53,218 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/07_authn-users-6500/cmd.txt 2024-04-15 00:02:53,218 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 00:02:53,998 INFO [loop_until]: OK (rc = 0) 2024-04-15 00:02:53,998 DEBUG --- stdout --- 2024-04-15 00:02:53,998 DEBUG tar: Removing leading `/' from member names 2024-04-15 00:02:53,998 DEBUG --- stderr --- 2024-04-15 00:02:53,998 DEBUG 2024-04-15 00:02:53,998 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-6500/cmd.txt is small (1 lines), printing all lines: 2024-04-15 00:02:53,998 DEBUG ----- output ----- 2024-04-15 00:02:53,999 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 00:02:53,999 INFO ________________________________________________________________________________ [2024-04-15 00:02:53] authn-users-6500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-15 00:02:53,999 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-6500/rc.txt is small (1 lines), printing all lines: 2024-04-15 00:02:53,999 DEBUG ----- output ----- 2024-04-15 00:02:53,999 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-15 00:02:53,999 INFO ________________________________________________________________________________ [2024-04-15 00:02:53] authn-users-6500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-15 00:02:54,000 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/07_authn-users-6500/stdout.txt is 5168 lines): 2024-04-15 00:02:54,000 DEBUG ----- output ----- 2024-04-15 00:02:54,000 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-15 00:02:54,000 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-15 00:02:54,000 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-15 00:02:54,000 INFO 2024-04-15 00:02:54,000 INFO ================================================================================ 2024-04-15 00:02:54,000 INFO 2024-04-15 00:52:34 5s elapsed 2024-04-15 00:02:54,000 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,000 INFO > Global (OK=2712 KO=0 ) 2024-04-15 00:02:54,000 INFO > userRestLogin (OK=2712 KO=0 ) 2024-04-15 00:02:54,000 INFO 2024-04-15 00:02:54,000 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,000 INFO [- ] 0% 2024-04-15 00:02:54,000 INFO waiting: 23490180 / active: 13 / done: 2711 2024-04-15 00:02:54,000 INFO ================================================================================ 2024-04-15 00:02:54,000 INFO 2024-04-15 00:02:54,000 INFO 2024-04-15 00:02:54,001 INFO ================================================================================ 2024-04-15 00:02:54,001 INFO 2024-04-15 00:52:39 10s elapsed 2024-04-15 00:02:54,001 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,001 INFO > Global (OK=10840 KO=0 ) 2024-04-15 00:02:54,001 INFO > userRestLogin (OK=10840 KO=0 ) 2024-04-15 00:02:54,001 INFO 2024-04-15 00:02:54,001 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,001 INFO [- ] 0% 2024-04-15 00:02:54,001 INFO waiting: 23482041 / active: 24 / done: 10839 2024-04-15 00:02:54,001 INFO ================================================================================ 2024-04-15 00:02:54,001 INFO 2024-04-15 00:02:54,001 INFO 2024-04-15 00:02:54,001 INFO ================================================================================ 2024-04-15 00:02:54,001 INFO 2024-04-15 00:52:44 15s elapsed 2024-04-15 00:02:54,001 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,001 INFO > Global (OK=24381 KO=0 ) 2024-04-15 00:02:54,001 INFO > userRestLogin (OK=24381 KO=0 ) 2024-04-15 00:02:54,001 INFO 2024-04-15 00:02:54,001 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,001 INFO [- ] 0% 2024-04-15 00:02:54,001 INFO waiting: 23468482 / active: 41 / done: 24381 2024-04-15 00:02:54,001 INFO ================================================================================ 2024-04-15 00:02:54,001 INFO 2024-04-15 00:02:54,001 INFO 2024-04-15 00:02:54,001 INFO ================================================================================ 2024-04-15 00:02:54,001 INFO 2024-04-15 00:52:49 20s elapsed 2024-04-15 00:02:54,001 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,001 INFO > Global (OK=43304 KO=0 ) 2024-04-15 00:02:54,001 INFO > userRestLogin (OK=43304 KO=0 ) 2024-04-15 00:02:54,001 INFO 2024-04-15 00:02:54,001 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,001 INFO [- ] 0% 2024-04-15 00:02:54,001 INFO waiting: 23449517 / active: 85 / done: 43302 2024-04-15 00:02:54,001 INFO ================================================================================ 2024-04-15 00:02:54,001 INFO 2024-04-15 00:02:54,001 INFO 2024-04-15 00:02:54,001 INFO ================================================================================ 2024-04-15 00:02:54,001 INFO 2024-04-15 00:52:54 25s elapsed 2024-04-15 00:02:54,001 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,001 INFO > Global (OK=67650 KO=0 ) 2024-04-15 00:02:54,001 INFO > userRestLogin (OK=67650 KO=0 ) 2024-04-15 00:02:54,002 INFO 2024-04-15 00:02:54,002 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,002 INFO [- ] 0% 2024-04-15 00:02:54,002 INFO waiting: 23425124 / active: 130 / done: 67650 2024-04-15 00:02:54,002 INFO ================================================================================ 2024-04-15 00:02:54,002 INFO 2024-04-15 00:02:54,002 INFO 2024-04-15 00:02:54,002 INFO ================================================================================ 2024-04-15 00:02:54,002 INFO 2024-04-15 00:52:59 30s elapsed 2024-04-15 00:02:54,002 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,002 INFO > Global (OK=97210 KO=0 ) 2024-04-15 00:02:54,002 INFO > userRestLogin (OK=97210 KO=0 ) 2024-04-15 00:02:54,002 INFO 2024-04-15 00:02:54,002 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,002 INFO [- ] 0% 2024-04-15 00:02:54,002 INFO waiting: 23395362 / active: 333 / done: 97209 2024-04-15 00:02:54,002 INFO ================================================================================ 2024-04-15 00:02:54,002 INFO 2024-04-15 00:02:54,002 INFO 2024-04-15 00:02:54,002 INFO ================================================================================ 2024-04-15 00:02:54,002 INFO 2024-04-15 00:53:04 35s elapsed 2024-04-15 00:02:54,002 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,002 INFO > Global (OK=128966 KO=0 ) 2024-04-15 00:02:54,002 INFO > userRestLogin (OK=128966 KO=0 ) 2024-04-15 00:02:54,002 INFO 2024-04-15 00:02:54,002 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,002 INFO [- ] 0% 2024-04-15 00:02:54,002 INFO waiting: 23362676 / active: 1262 / done: 128966 2024-04-15 00:02:54,002 INFO ================================================================================ 2024-04-15 00:02:54,002 INFO 2024-04-15 00:02:54,002 INFO 2024-04-15 00:02:54,002 INFO ================================================================================ 2024-04-15 00:02:54,002 INFO 2024-04-15 00:53:09 40s elapsed 2024-04-15 00:02:54,002 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,002 INFO > Global (OK=160995 KO=0 ) 2024-04-15 00:02:54,002 INFO > userRestLogin (OK=160995 KO=0 ) 2024-04-15 00:02:54,002 INFO 2024-04-15 00:02:54,002 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,002 INFO [- ] 0% 2024-04-15 00:02:54,002 INFO waiting: 23330252 / active: 1658 / done: 160994 2024-04-15 00:02:54,003 INFO ================================================================================ 2024-04-15 00:02:54,003 INFO 2024-04-15 00:02:54,003 INFO 2024-04-15 00:02:54,003 INFO [...] 2024-04-15 00:02:54,004 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,004 INFO > Global (OK=2508346 KO=171 ) 2024-04-15 00:02:54,004 INFO > userRestLogin (OK=2508346 KO=171 ) 2024-04-15 00:02:54,004 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 00:02:54,004 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 170 (99.42%) 2024-04-15 00:02:54,004 INFO .47.242:443 after 60000 ms 2024-04-15 00:02:54,004 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 ( 0.58%) 2024-04-15 00:02:54,004 INFO ), but actually found 502 2024-04-15 00:02:54,004 INFO 2024-04-15 00:02:54,004 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,004 INFO [#######-- ] 10% 2024-04-15 00:02:54,004 INFO waiting: 20644501 / active: 339887 / done: 2508516 2024-04-15 00:02:54,004 INFO ================================================================================ 2024-04-15 00:02:54,004 INFO 2024-04-15 00:02:54,004 INFO 2024-04-15 00:02:54,004 INFO ================================================================================ 2024-04-15 00:02:54,004 INFO 2024-04-15 01:01:04 515s elapsed 2024-04-15 00:02:54,004 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,004 INFO > Global (OK=2523859 KO=171 ) 2024-04-15 00:02:54,004 INFO > userRestLogin (OK=2523859 KO=171 ) 2024-04-15 00:02:54,005 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 00:02:54,005 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 170 (99.42%) 2024-04-15 00:02:54,005 INFO .47.242:443 after 60000 ms 2024-04-15 00:02:54,005 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 ( 0.58%) 2024-04-15 00:02:54,005 INFO ), but actually found 502 2024-04-15 00:02:54,005 INFO 2024-04-15 00:02:54,005 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,005 INFO [#######-- ] 10% 2024-04-15 00:02:54,005 INFO waiting: 20606757 / active: 362118 / done: 2524029 2024-04-15 00:02:54,005 INFO ================================================================================ 2024-04-15 00:02:54,005 INFO 2024-04-15 00:02:54,005 INFO 2024-04-15 00:02:54,005 INFO ================================================================================ 2024-04-15 00:02:54,005 INFO 2024-04-15 01:01:09 520s elapsed 2024-04-15 00:02:54,005 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,005 INFO > Global (OK=2538627 KO=171 ) 2024-04-15 00:02:54,005 INFO > userRestLogin (OK=2538627 KO=171 ) 2024-04-15 00:02:54,005 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 00:02:54,005 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 170 (99.42%) 2024-04-15 00:02:54,005 INFO .47.242:443 after 60000 ms 2024-04-15 00:02:54,005 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 ( 0.58%) 2024-04-15 00:02:54,005 INFO ), but actually found 502 2024-04-15 00:02:54,005 INFO 2024-04-15 00:02:54,005 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,005 INFO [#######-- ] 10% 2024-04-15 00:02:54,005 INFO waiting: 20590495 / active: 363612 / done: 2538797 2024-04-15 00:02:54,005 INFO ================================================================================ 2024-04-15 00:02:54,005 INFO 2024-04-15 00:02:54,005 INFO 2024-04-15 00:02:54,005 INFO ================================================================================ 2024-04-15 00:02:54,005 INFO 2024-04-15 01:01:14 525s elapsed 2024-04-15 00:02:54,005 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,005 INFO > Global (OK=2554928 KO=171 ) 2024-04-15 00:02:54,005 INFO > userRestLogin (OK=2554928 KO=171 ) 2024-04-15 00:02:54,005 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 00:02:54,005 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 170 (99.42%) 2024-04-15 00:02:54,005 INFO .47.242:443 after 60000 ms 2024-04-15 00:02:54,006 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 ( 0.58%) 2024-04-15 00:02:54,006 INFO ), but actually found 502 2024-04-15 00:02:54,006 INFO 2024-04-15 00:02:54,006 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,006 INFO [########-- ] 10% 2024-04-15 00:02:54,006 INFO waiting: 20571390 / active: 366416 / done: 2555098 2024-04-15 00:02:54,006 INFO ================================================================================ 2024-04-15 00:02:54,006 INFO 2024-04-15 00:02:54,006 INFO 2024-04-15 00:02:54,006 INFO ================================================================================ 2024-04-15 00:02:54,006 INFO 2024-04-15 01:01:19 530s elapsed 2024-04-15 00:02:54,006 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 00:02:54,006 INFO > Global (OK=2569946 KO=171 ) 2024-04-15 00:02:54,006 INFO > userRestLogin (OK=2569946 KO=171 ) 2024-04-15 00:02:54,006 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 00:02:54,006 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 170 (99.42%) 2024-04-15 00:02:54,006 INFO .47.242:443 after 60000 ms 2024-04-15 00:02:54,006 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 ( 0.58%) 2024-04-15 00:02:54,006 INFO ), but actually found 502 2024-04-15 00:02:54,006 INFO 2024-04-15 00:02:54,006 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 00:02:54,006 INFO [########-- ] 10% 2024-04-15 00:02:54,006 INFO waiting: 20484122 / active: 438665 / done: 2570117 2024-04-15 00:02:54,006 INFO ================================================================================ 2024-04-15 00:02:54,006 INFO 2024-04-15 00:02:54,006 INFO 2024-04-15 00:02:54,006 INFO ---------------------- 2024-04-15 00:02:54,006 INFO [15/Apr/2024 01:01:22] Create tar file authn-users-6500.tar.gz 2024-04-15 00:02:54,006 INFO ---------------------- 2024-04-15 00:02:54,006 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-15 00:02:54,006 INFO RESULT_FOLDER=authn-users-6500 2024-04-15 00:02:54,006 INFO SIMULATION_PATH=authn-users-6500/loadtest-20240414235229100 2024-04-15 00:02:54,006 INFO PWD=/results/gatling/authn-users-6500/loadtest-20240414235229100 2024-04-15 00:02:54,006 INFO [cmd] tar -czvf /results/gatling//authn-users-6500.tar.gz --exclude=simulation.log * 2024-04-15 00:02:54,006 INFO /results/gatling 2024-04-15 00:02:54,006 INFO 2024-04-15 00:02:54,007 INFO ---------------------- 2024-04-15 00:02:54,007 INFO [15/Apr/2024 01:01:22] Update /results/gatling//latest 2024-04-15 00:02:54,007 INFO ---------------------- 2024-04-15 00:02:54,007 INFO 2024-04-15 00:02:54,007 INFO ---------------------- 2024-04-15 00:02:54,007 INFO [15/Apr/2024 01:01:22] Update /results/gatling//latest.tar.gz 2024-04-15 00:02:54,007 INFO ---------------------- 2024-04-15 00:02:54,007 INFO ________________________________________________________________________________ [2024-04-15 00:02:54] authn-users-6500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-15 00:02:54,007 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/07_authn-users-6500/stderr.txt is small (0 lines), printing all lines: 2024-04-15 00:02:54,007 DEBUG ----- output ----- 2024-04-15 00:02:54,007 DEBUG 2024-04-15 00:02:54,007 INFO ________________________________________________________________________________ [2024-04-15 00:02:54] 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 00:02:54] 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 00:02:54] authn-users-6500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped