--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 21:30:22] authn-users-6500 pre : N/A ________________________________________________________________________________ [2024-04-15 21:30:22] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-15 21:30:22] - 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 21:30:22,562 INFO 2024-04-15 21:30:22,563 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 21:30:22,563 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:22,904 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:22,905 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:22,905 DEBUG --- stdout --- 2024-04-15 21:30:22,905 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:22,905 DEBUG --- stderr --- 2024-04-15 21:30:22,905 DEBUG ________________________________________________________________________________ [2024-04-15 21:30:22] authn-users-6500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-15 21:30:22,906 INFO 2024-04-15 21:30:22,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 21:30:22,906 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:23,235 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:23,236 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:23,236 DEBUG --- stdout --- 2024-04-15 21:30:23,236 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:23,236 DEBUG --- stderr --- 2024-04-15 21:30:23,236 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 21:30:23] authn-users-6500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-15 21:30:23,237 INFO 2024-04-15 21:30:23,237 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 21:30:23,237 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:23,565 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:23,565 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:23,565 DEBUG --- stdout --- 2024-04-15 21:30:23,565 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:23,565 DEBUG --- stderr --- 2024-04-15 21:30:23,565 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 21:30:23] 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 21:30:23,568 INFO 2024-04-15 21:30:23,568 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-15 21:30:23,568 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-15 21:30:23,854 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 21:30:23,855 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:23,855 DEBUG --- stdout --- 2024-04-15 21:30:23,855 DEBUG WAITING 2024-04-15 21:30:23,855 DEBUG --- stderr --- 2024-04-15 21:30:23,855 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 27 0 --:--:-- --:--:-- --:--:-- 27 ________________________________________________________________________________ [2024-04-15 21:30:23] authn-users-6500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-15 21:30:23,856 INFO 2024-04-15 21:30:23,856 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 21:30:23,856 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:24,189 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:24,189 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:24,189 DEBUG --- stdout --- 2024-04-15 21:30:24,189 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:24,189 DEBUG --- stderr --- 2024-04-15 21:30:24,189 DEBUG 2024-04-15 21:30:24,190 INFO 2024-04-15 21:30:24,190 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 21:30:24,190 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 21:30:25,593 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:25,593 DEBUG --- stdout --- 2024-04-15 21:30:25,595 DEBUG 2024-04-15 21:30:25,595 DEBUG --- stderr --- 2024-04-15 21:30:25,595 DEBUG Order has been successfully forwarded to overseer [2024-04-15 21:30:25] - INFO: Check if Gatling simulation started successfully 2024-04-15 21:30:25,596 INFO 2024-04-15 21:30:25,596 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 21:30:25,596 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:25,973 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:25,974 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:25,974 DEBUG --- stdout --- 2024-04-15 21:30:25,974 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:25,974 DEBUG --- stderr --- 2024-04-15 21:30:25,974 DEBUG 2024-04-15 21:30:25,974 INFO 2024-04-15 21:30:25,975 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 21:30:25,975 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-15 21:30:26,768 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-15 21:30:32,589 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-15 21:30:38,426 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-15 21:30:44,240 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-15 21:30:44,241 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:44,241 DEBUG --- stdout --- 2024-04-15 21:30:44,241 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 22:30:28.890 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 22:30:35.037 [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"); ^ 22:30:35.040 [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"); ^ 22:30:37.430 [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 21:30:44,241 DEBUG --- stderr --- 2024-04-15 21:30:44,241 DEBUG ________________________________________________________________________________ [2024-04-15 21:30:44] 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 31s, retry Order is running since 2m 36s, retry Order is running since 2m 42s, retry Order is running since 2m 47s, retry Order is running since 2m 52s, 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 Order is running since 5m 19s, retry Order is running since 5m 25s, retry Order is running since 5m 30s, retry Order is running since 5m 35s, retry Order is running since 5m 40s, retry Order is running since 5m 45s, retry PASS : Order stopped by itself after 5m 51s and before timeout (36m) ________________________________________________________________________________ [2024-04-15 21:36:35] authn-users-6500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-15 21:36:35,327 INFO 2024-04-15 21:36:35,327 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 21:36:35,327 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:35,738 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:35,739 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:35,739 DEBUG --- stdout --- 2024-04-15 21:36:35,739 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:35,739 DEBUG --- stderr --- 2024-04-15 21:36:35,739 DEBUG 2024-04-15 21:36:35,739 INFO 2024-04-15 21:36:35,739 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 21:36:35,739 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 21:36:36,605 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:36,606 DEBUG --- stdout --- 2024-04-15 21:36:36,606 DEBUG 2024-04-15 21:36:36,606 DEBUG --- stderr --- 2024-04-15 21:36:36,606 DEBUG Order ran during 6m 11s ________________________________________________________________________________ [2024-04-15 21:36:36] authn-users-6500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-15 21:36:36] - 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 21:36:36,607 INFO 2024-04-15 21:36:36,607 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 21:36:36,608 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:36,991 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:36,991 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:36,991 DEBUG --- stdout --- 2024-04-15 21:36:36,991 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:36,991 DEBUG --- stderr --- 2024-04-15 21:36:36,991 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 21:36:36,992 INFO 2024-04-15 21:36:36,992 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 21:36:36,992 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:37,325 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:37,325 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:37,325 DEBUG --- stdout --- 2024-04-15 21:36:37,326 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:37,326 DEBUG --- stderr --- 2024-04-15 21:36:37,326 DEBUG 2024-04-15 21:36:37,326 INFO 2024-04-15 21:36:37,326 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 21:36:37,326 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 21:36:38,171 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 21:36:38,172 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:38,172 DEBUG --- stdout --- 2024-04-15 21:36:38,172 DEBUG /results/orders/authn-users-6500/rc.txt file found 2024-04-15 21:36:38,172 DEBUG --- stderr --- 2024-04-15 21:36:38,172 DEBUG 2024-04-15 21:36:38,172 INFO 2024-04-15 21:36:38,172 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 21:36:38,172 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:38,483 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:38,483 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:38,483 DEBUG --- stdout --- 2024-04-15 21:36:38,483 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:38,483 DEBUG --- stderr --- 2024-04-15 21:36:38,483 DEBUG 2024-04-15 21:36:38,483 INFO 2024-04-15 21:36:38,483 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 21:36:39,336 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 21:36:39,336 DEBUG --- stdout --- 2024-04-15 21:36:39,336 DEBUG 4 /results/orders/authn-users-6500/rc.txt 2024-04-15 21:36:39,337 DEBUG --- stderr --- 2024-04-15 21:36:39,337 DEBUG 2024-04-15 21:36:39,337 INFO - Download file (size 4k) 2024-04-15 21:36:39,337 INFO 2024-04-15 21:36:39,337 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 21:36:39,337 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:39,674 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:39,675 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:39,675 DEBUG --- stdout --- 2024-04-15 21:36:39,675 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:39,675 DEBUG --- stderr --- 2024-04-15 21:36:39,675 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 21:36:39,676 INFO 2024-04-15 21:36:39,676 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 21:36:39,676 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:40,028 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:40,028 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:40,028 DEBUG --- stdout --- 2024-04-15 21:36:40,028 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:40,028 DEBUG --- stderr --- 2024-04-15 21:36:40,028 DEBUG 2024-04-15 21:36:40,029 INFO 2024-04-15 21:36:40,029 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 21:36:40,029 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 21:36:40,818 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:40,819 DEBUG --- stdout --- 2024-04-15 21:36:40,819 DEBUG tar: Removing leading `/' from member names 2024-04-15 21:36:40,819 DEBUG --- stderr --- 2024-04-15 21:36:40,819 DEBUG 2024-04-15 21:36:40,819 INFO 2024-04-15 21:36:40,819 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 21:36:40,819 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:41,126 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:41,126 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:41,126 DEBUG --- stdout --- 2024-04-15 21:36:41,126 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:41,126 DEBUG --- stderr --- 2024-04-15 21:36:41,126 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 21:36:41,127 INFO 2024-04-15 21:36:41,127 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 21:36:41,127 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:41,441 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:41,441 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:41,441 DEBUG --- stdout --- 2024-04-15 21:36:41,441 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:41,441 DEBUG --- stderr --- 2024-04-15 21:36:41,441 DEBUG 2024-04-15 21:36:41,442 INFO 2024-04-15 21:36:41,442 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 21:36:41,442 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 21:36:42,347 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 21:36:42,348 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:42,348 DEBUG --- stdout --- 2024-04-15 21:36:42,348 DEBUG /results/orders/authn-users-6500/stderr.txt file found 2024-04-15 21:36:42,348 DEBUG --- stderr --- 2024-04-15 21:36:42,348 DEBUG 2024-04-15 21:36:42,349 INFO 2024-04-15 21:36:42,349 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 21:36:42,349 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:42,682 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:42,682 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:42,682 DEBUG --- stdout --- 2024-04-15 21:36:42,682 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:42,682 DEBUG --- stderr --- 2024-04-15 21:36:42,682 DEBUG 2024-04-15 21:36:42,683 INFO 2024-04-15 21:36:42,683 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 21:36:43,531 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 21:36:43,533 DEBUG --- stdout --- 2024-04-15 21:36:43,533 DEBUG 0 /results/orders/authn-users-6500/stderr.txt 2024-04-15 21:36:43,533 DEBUG --- stderr --- 2024-04-15 21:36:43,533 DEBUG 2024-04-15 21:36:43,533 INFO - Download file (size 0k) 2024-04-15 21:36:43,533 INFO 2024-04-15 21:36:43,533 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 21:36:43,533 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:43,867 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:43,868 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:43,868 DEBUG --- stdout --- 2024-04-15 21:36:43,868 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:43,868 DEBUG --- stderr --- 2024-04-15 21:36:43,868 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 21:36:43,868 INFO 2024-04-15 21:36:43,868 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 21:36:43,868 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:44,199 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:44,199 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:44,199 DEBUG --- stdout --- 2024-04-15 21:36:44,199 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:44,199 DEBUG --- stderr --- 2024-04-15 21:36:44,199 DEBUG 2024-04-15 21:36:44,200 INFO 2024-04-15 21:36:44,200 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 21:36:44,200 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 21:36:44,950 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:44,950 DEBUG --- stdout --- 2024-04-15 21:36:44,950 DEBUG tar: Removing leading `/' from member names 2024-04-15 21:36:44,951 DEBUG --- stderr --- 2024-04-15 21:36:44,951 DEBUG 2024-04-15 21:36:44,951 INFO 2024-04-15 21:36:44,951 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 21:36:44,951 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:45,265 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:45,265 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:45,265 DEBUG --- stdout --- 2024-04-15 21:36:45,265 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:45,266 DEBUG --- stderr --- 2024-04-15 21:36:45,266 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 21:36:45,267 INFO 2024-04-15 21:36:45,267 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 21:36:45,267 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:45,578 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:45,579 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:45,579 DEBUG --- stdout --- 2024-04-15 21:36:45,579 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:45,579 DEBUG --- stderr --- 2024-04-15 21:36:45,579 DEBUG 2024-04-15 21:36:45,579 INFO 2024-04-15 21:36:45,579 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 21:36:45,579 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 21:36:46,404 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 21:36:46,404 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:46,404 DEBUG --- stdout --- 2024-04-15 21:36:46,404 DEBUG /results/orders/authn-users-6500/stdout.txt file found 2024-04-15 21:36:46,404 DEBUG --- stderr --- 2024-04-15 21:36:46,404 DEBUG 2024-04-15 21:36:46,404 INFO 2024-04-15 21:36:46,404 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 21:36:46,404 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:46,715 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:46,716 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:46,716 DEBUG --- stdout --- 2024-04-15 21:36:46,716 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:46,716 DEBUG --- stderr --- 2024-04-15 21:36:46,716 DEBUG 2024-04-15 21:36:46,716 INFO 2024-04-15 21:36:46,717 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 21:36:47,565 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 21:36:47,565 DEBUG --- stdout --- 2024-04-15 21:36:47,565 DEBUG 252 /results/orders/authn-users-6500/stdout.txt 2024-04-15 21:36:47,565 DEBUG --- stderr --- 2024-04-15 21:36:47,565 DEBUG 2024-04-15 21:36:47,565 INFO - Download file (size 252k) 2024-04-15 21:36:47,566 INFO 2024-04-15 21:36:47,566 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 21:36:47,566 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:47,897 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:47,897 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:47,897 DEBUG --- stdout --- 2024-04-15 21:36:47,897 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:47,897 DEBUG --- stderr --- 2024-04-15 21:36:47,897 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 21:36:47,898 INFO 2024-04-15 21:36:47,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 21:36:47,898 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:48,223 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:48,223 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:48,223 DEBUG --- stdout --- 2024-04-15 21:36:48,223 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:48,223 DEBUG --- stderr --- 2024-04-15 21:36:48,223 DEBUG 2024-04-15 21:36:48,224 INFO 2024-04-15 21:36:48,224 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 21:36:48,224 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 21:36:49,258 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:49,259 DEBUG --- stdout --- 2024-04-15 21:36:49,259 DEBUG tar: Removing leading `/' from member names 2024-04-15 21:36:49,259 DEBUG --- stderr --- 2024-04-15 21:36:49,259 DEBUG ________________________________________________________________________________ [2024-04-15 21:36:49] authn-users-6500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-15 21:36:49,260 INFO 2024-04-15 21:36:49,260 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 21:36:49,260 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:49,623 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:49,623 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:49,624 DEBUG --- stdout --- 2024-04-15 21:36:49,624 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:49,624 DEBUG --- stderr --- 2024-04-15 21:36:49,624 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 21:36:49,624 INFO 2024-04-15 21:36:49,624 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 21:36:49,624 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:49,974 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:49,975 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:49,975 DEBUG --- stdout --- 2024-04-15 21:36:49,975 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:49,975 DEBUG --- stderr --- 2024-04-15 21:36:49,975 DEBUG 2024-04-15 21:36:49,976 INFO 2024-04-15 21:36:49,976 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 21:36:49,976 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 21:36:50,860 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 21:36:50,860 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:50,860 DEBUG --- stdout --- 2024-04-15 21:36:50,860 DEBUG /results/orders/authn-users-6500/cmd.txt file found 2024-04-15 21:36:50,860 DEBUG --- stderr --- 2024-04-15 21:36:50,860 DEBUG 2024-04-15 21:36:50,861 INFO 2024-04-15 21:36:50,861 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 21:36:50,861 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:51,173 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:51,173 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:51,173 DEBUG --- stdout --- 2024-04-15 21:36:51,173 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:51,173 DEBUG --- stderr --- 2024-04-15 21:36:51,173 DEBUG 2024-04-15 21:36:51,173 INFO 2024-04-15 21:36:51,174 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 21:36:52,003 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 21:36:52,004 DEBUG --- stdout --- 2024-04-15 21:36:52,004 DEBUG 4 /results/orders/authn-users-6500/cmd.txt 2024-04-15 21:36:52,004 DEBUG --- stderr --- 2024-04-15 21:36:52,004 DEBUG 2024-04-15 21:36:52,004 INFO - Download file (size 4k) 2024-04-15 21:36:52,004 INFO 2024-04-15 21:36:52,004 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 21:36:52,004 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:52,341 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:52,341 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:52,341 DEBUG --- stdout --- 2024-04-15 21:36:52,341 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:52,341 DEBUG --- stderr --- 2024-04-15 21:36:52,341 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 21:36:52,342 INFO 2024-04-15 21:36:52,342 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 21:36:52,342 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:36:52,679 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:36:52,680 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:52,680 DEBUG --- stdout --- 2024-04-15 21:36:52,680 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:36:52,680 DEBUG --- stderr --- 2024-04-15 21:36:52,680 DEBUG 2024-04-15 21:36:52,680 INFO 2024-04-15 21:36:52,680 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 21:36:52,680 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 21:36:53,441 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:36:53,442 DEBUG --- stdout --- 2024-04-15 21:36:53,442 DEBUG tar: Removing leading `/' from member names 2024-04-15 21:36:53,442 DEBUG --- stderr --- 2024-04-15 21:36:53,442 DEBUG 2024-04-15 21:36:53,443 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 21:36:53,443 DEBUG ----- output ----- 2024-04-15 21:36:53,443 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 21:36:53,443 INFO ________________________________________________________________________________ [2024-04-15 21:36:53] authn-users-6500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-15 21:36:53,444 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 21:36:53,444 DEBUG ----- output ----- 2024-04-15 21:36:53,444 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-15 21:36:53,444 INFO ________________________________________________________________________________ [2024-04-15 21:36:53] authn-users-6500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-15 21:36:53,445 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 3952 lines): 2024-04-15 21:36:53,445 DEBUG ----- output ----- 2024-04-15 21:36:53,445 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-15 21:36:53,445 INFO 22:30:28.890 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-15 21:36:53,445 INFO 22:30:35.037 [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 21:36:53,445 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-15 21:36:53,445 INFO ^ 2024-04-15 21:36:53,445 INFO 22:30:35.040 [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 21:36:53,445 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-15 21:36:53,445 INFO ^ 2024-04-15 21:36:53,445 INFO 22:30:37.430 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-15 21:36:53,445 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-15 21:36:53,445 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-15 21:36:53,445 INFO 2024-04-15 21:36:53,445 INFO ================================================================================ 2024-04-15 21:36:53,445 INFO 2024-04-15 22:30:47 5s elapsed 2024-04-15 21:36:53,445 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,445 INFO > Global (OK=2709 KO=0 ) 2024-04-15 21:36:53,445 INFO > userRestLogin (OK=2709 KO=0 ) 2024-04-15 21:36:53,445 INFO 2024-04-15 21:36:53,446 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,446 INFO [- ] 0% 2024-04-15 21:36:53,446 INFO waiting: 11792947 / active: 16 / done: 2709 2024-04-15 21:36:53,446 INFO ================================================================================ 2024-04-15 21:36:53,446 INFO 2024-04-15 21:36:53,446 INFO 2024-04-15 21:36:53,446 INFO ================================================================================ 2024-04-15 21:36:53,446 INFO 2024-04-15 22:30:52 10s elapsed 2024-04-15 21:36:53,446 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,446 INFO > Global (OK=10842 KO=0 ) 2024-04-15 21:36:53,446 INFO > userRestLogin (OK=10842 KO=0 ) 2024-04-15 21:36:53,446 INFO 2024-04-15 21:36:53,446 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,446 INFO [- ] 0% 2024-04-15 21:36:53,446 INFO waiting: 11784807 / active: 23 / done: 10842 2024-04-15 21:36:53,446 INFO ================================================================================ 2024-04-15 21:36:53,446 INFO 2024-04-15 21:36:53,446 INFO 2024-04-15 21:36:53,446 INFO ================================================================================ 2024-04-15 21:36:53,446 INFO 2024-04-15 22:30:57 15s elapsed 2024-04-15 21:36:53,446 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,446 INFO > Global (OK=24381 KO=0 ) 2024-04-15 21:36:53,446 INFO > userRestLogin (OK=24381 KO=0 ) 2024-04-15 21:36:53,446 INFO 2024-04-15 21:36:53,446 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,446 INFO [- ] 0% 2024-04-15 21:36:53,446 INFO waiting: 11771246 / active: 45 / done: 24381 2024-04-15 21:36:53,446 INFO ================================================================================ 2024-04-15 21:36:53,446 INFO 2024-04-15 21:36:53,446 INFO 2024-04-15 21:36:53,447 INFO ================================================================================ 2024-04-15 21:36:53,447 INFO 2024-04-15 22:31:02 20s elapsed 2024-04-15 21:36:53,447 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,447 INFO > Global (OK=43338 KO=0 ) 2024-04-15 21:36:53,447 INFO > userRestLogin (OK=43338 KO=0 ) 2024-04-15 21:36:53,447 INFO 2024-04-15 21:36:53,447 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,447 INFO [- ] 0% 2024-04-15 21:36:53,447 INFO waiting: 11752276 / active: 60 / done: 43336 2024-04-15 21:36:53,447 INFO ================================================================================ 2024-04-15 21:36:53,447 INFO 2024-04-15 21:36:53,447 INFO 2024-04-15 21:36:53,447 INFO ================================================================================ 2024-04-15 21:36:53,447 INFO 2024-04-15 22:31:07 25s elapsed 2024-04-15 21:36:53,447 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,447 INFO > Global (OK=67646 KO=0 ) 2024-04-15 21:36:53,447 INFO > userRestLogin (OK=67646 KO=0 ) 2024-04-15 21:36:53,447 INFO 2024-04-15 21:36:53,447 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,447 INFO [- ] 0% 2024-04-15 21:36:53,447 INFO waiting: 11727886 / active: 141 / done: 67645 2024-04-15 21:36:53,447 INFO ================================================================================ 2024-04-15 21:36:53,447 INFO 2024-04-15 21:36:53,447 INFO 2024-04-15 21:36:53,447 INFO ================================================================================ 2024-04-15 21:36:53,447 INFO 2024-04-15 22:31:12 30s elapsed 2024-04-15 21:36:53,447 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,447 INFO > Global (OK=97164 KO=0 ) 2024-04-15 21:36:53,447 INFO > userRestLogin (OK=97164 KO=0 ) 2024-04-15 21:36:53,447 INFO 2024-04-15 21:36:53,447 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,447 INFO [- ] 0% 2024-04-15 21:36:53,447 INFO waiting: 11698090 / active: 419 / done: 97163 2024-04-15 21:36:53,447 INFO ================================================================================ 2024-04-15 21:36:53,447 INFO 2024-04-15 21:36:53,447 INFO 2024-04-15 21:36:53,447 INFO ================================================================================ 2024-04-15 21:36:53,447 INFO 2024-04-15 22:31:17 35s elapsed 2024-04-15 21:36:53,447 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,447 INFO > Global (OK=128839 KO=0 ) 2024-04-15 21:36:53,447 INFO > userRestLogin (OK=128839 KO=0 ) 2024-04-15 21:36:53,448 INFO 2024-04-15 21:36:53,448 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,448 INFO [- ] 1% 2024-04-15 21:36:53,448 INFO waiting: 11665456 / active: 1379 / done: 128837 2024-04-15 21:36:53,448 INFO ================================================================================ 2024-04-15 21:36:53,448 INFO 2024-04-15 21:36:53,448 INFO 2024-04-15 21:36:53,448 INFO ================================================================================ 2024-04-15 21:36:53,448 INFO 2024-04-15 22:31:22 40s elapsed 2024-04-15 21:36:53,448 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,448 INFO > Global (OK=160668 KO=0 ) 2024-04-15 21:36:53,448 INFO [...] 2024-04-15 21:36:53,449 INFO accept: */* 2024-04-15 21:36:53,449 INFO host: xlou.iam.xlou-cdm.engineeringpit.com 2024-04-15 21:36:53,449 INFO content-length: 0 2024-04-15 21:36:53,449 INFO ========================= 2024-04-15 21:36:53,449 INFO HTTP response: 2024-04-15 21:36:53,449 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-04-15 21:36:53,449 INFO 22:36:06.965 [ERROR] i.g.c.action.If - 'if-2' failed to execute: j.u.NoSuchElementException: No attribute named 'auth_resp_code' is defined 2024-04-15 21:36:53,449 INFO 2024-04-15 21:36:53,449 INFO ================================================================================ 2024-04-15 21:36:53,449 INFO 2024-04-15 22:36:07 325s elapsed 2024-04-15 21:36:53,449 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,449 INFO > Global (OK=1347323 KO=140 ) 2024-04-15 21:36:53,449 INFO > userRestLogin (OK=1347323 KO=140 ) 2024-04-15 21:36:53,449 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 21:36:53,449 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 140 (100.0%) 2024-04-15 21:36:53,449 INFO .47.242:443 after 60000 ms 2024-04-15 21:36:53,449 INFO 2024-04-15 21:36:53,449 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,449 INFO [########--- ] 11% 2024-04-15 21:36:53,449 INFO waiting: 10089737 / active: 358474 / done: 1347461 2024-04-15 21:36:53,449 INFO ================================================================================ 2024-04-15 21:36:53,449 INFO 2024-04-15 21:36:53,449 INFO 2024-04-15 21:36:53,450 INFO ================================================================================ 2024-04-15 21:36:53,450 INFO 2024-04-15 22:36:12 330s elapsed 2024-04-15 21:36:53,450 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,450 INFO > Global (OK=1364691 KO=140 ) 2024-04-15 21:36:53,450 INFO > userRestLogin (OK=1364691 KO=140 ) 2024-04-15 21:36:53,450 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 21:36:53,450 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 140 (100.0%) 2024-04-15 21:36:53,450 INFO .47.242:443 after 60000 ms 2024-04-15 21:36:53,450 INFO 2024-04-15 21:36:53,450 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,450 INFO [########--- ] 11% 2024-04-15 21:36:53,450 INFO waiting: 10047068 / active: 383774 / done: 1364830 2024-04-15 21:36:53,450 INFO ================================================================================ 2024-04-15 21:36:53,450 INFO 2024-04-15 21:36:53,450 INFO 2024-04-15 21:36:53,450 INFO ================================================================================ 2024-04-15 21:36:53,450 INFO 2024-04-15 22:36:17 335s elapsed 2024-04-15 21:36:53,450 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,450 INFO > Global (OK=1382064 KO=140 ) 2024-04-15 21:36:53,450 INFO > userRestLogin (OK=1382064 KO=140 ) 2024-04-15 21:36:53,450 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 21:36:53,450 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 140 (100.0%) 2024-04-15 21:36:53,450 INFO .47.242:443 after 60000 ms 2024-04-15 21:36:53,450 INFO 2024-04-15 21:36:53,450 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,450 INFO [########--- ] 11% 2024-04-15 21:36:53,450 INFO waiting: 10008972 / active: 404498 / done: 1382202 2024-04-15 21:36:53,450 INFO ================================================================================ 2024-04-15 21:36:53,450 INFO 2024-04-15 21:36:53,450 INFO 2024-04-15 21:36:53,450 INFO ================================================================================ 2024-04-15 21:36:53,450 INFO 2024-04-15 22:36:22 340s elapsed 2024-04-15 21:36:53,450 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,450 INFO > Global (OK=1398180 KO=140 ) 2024-04-15 21:36:53,450 INFO > userRestLogin (OK=1398180 KO=140 ) 2024-04-15 21:36:53,450 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 21:36:53,450 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 140 (100.0%) 2024-04-15 21:36:53,450 INFO .47.242:443 after 60000 ms 2024-04-15 21:36:53,450 INFO 2024-04-15 21:36:53,451 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,451 INFO [########--- ] 11% 2024-04-15 21:36:53,451 INFO waiting: 9937373 / active: 459980 / done: 1398319 2024-04-15 21:36:53,451 INFO ================================================================================ 2024-04-15 21:36:53,451 INFO 2024-04-15 21:36:53,451 INFO 2024-04-15 21:36:53,451 INFO ================================================================================ 2024-04-15 21:36:53,451 INFO 2024-04-15 22:36:27 345s elapsed 2024-04-15 21:36:53,451 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:36:53,451 INFO > Global (OK=1411756 KO=140 ) 2024-04-15 21:36:53,451 INFO > userRestLogin (OK=1411756 KO=140 ) 2024-04-15 21:36:53,451 INFO ---- Errors -------------------------------------------------------------------- 2024-04-15 21:36:53,451 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 140 (100.0%) 2024-04-15 21:36:53,451 INFO .47.242:443 after 60000 ms 2024-04-15 21:36:53,451 INFO 2024-04-15 21:36:53,451 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:36:53,451 INFO [########--- ] 11% 2024-04-15 21:36:53,451 INFO waiting: 9920304 / active: 463472 / done: 1411896 2024-04-15 21:36:53,451 INFO ================================================================================ 2024-04-15 21:36:53,451 INFO 2024-04-15 21:36:53,451 INFO 2024-04-15 21:36:53,451 INFO ---------------------- 2024-04-15 21:36:53,451 INFO [15/Apr/2024 22:36:29] Create tar file authn-users-6500.tar.gz 2024-04-15 21:36:53,451 INFO ---------------------- 2024-04-15 21:36:53,451 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-15 21:36:53,451 INFO RESULT_FOLDER=authn-users-6500 2024-04-15 21:36:53,451 INFO SIMULATION_PATH=authn-users-6500/loadtest-20240415213041661 2024-04-15 21:36:53,451 INFO PWD=/results/gatling/authn-users-6500/loadtest-20240415213041661 2024-04-15 21:36:53,452 INFO [cmd] tar -czvf /results/gatling//authn-users-6500.tar.gz --exclude=simulation.log * 2024-04-15 21:36:53,452 INFO /results/gatling 2024-04-15 21:36:53,452 INFO 2024-04-15 21:36:53,452 INFO ---------------------- 2024-04-15 21:36:53,452 INFO [15/Apr/2024 22:36:29] Update /results/gatling//latest 2024-04-15 21:36:53,452 INFO ---------------------- 2024-04-15 21:36:53,452 INFO 2024-04-15 21:36:53,452 INFO ---------------------- 2024-04-15 21:36:53,452 INFO [15/Apr/2024 22:36:29] Update /results/gatling//latest.tar.gz 2024-04-15 21:36:53,452 INFO ---------------------- 2024-04-15 21:36:53,452 INFO ________________________________________________________________________________ [2024-04-15 21:36:53] authn-users-6500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-15 21:36:53,452 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 21:36:53,452 DEBUG ----- output ----- 2024-04-15 21:36:53,452 DEBUG 2024-04-15 21:36:53,452 INFO ________________________________________________________________________________ [2024-04-15 21:36:53] 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 21:36:53] 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 21:36:53] authn-users-6500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped