--Task-- name: authn-users-3000 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['authn-users-100'] preceding_task: authn-users-100 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 3000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-19 06:50:02] authn-users-3000 pre : N/A ________________________________________________________________________________ [2024-04-19 06:50:02] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-19 06:50:02] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-3000/stdout.txt Pod log : /results/orders/authn-users-3000 2024-04-19 06:50:02,039 INFO 2024-04-19 06:50:02,039 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-19 06:50:02,039 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:50:02,386 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:50:02,386 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:50:02,386 DEBUG --- stdout --- 2024-04-19 06:50:02,386 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:50:02,386 DEBUG --- stderr --- 2024-04-19 06:50:02,386 DEBUG ________________________________________________________________________________ [2024-04-19 06:50:02] authn-users-3000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-19 06:50:02,387 INFO 2024-04-19 06:50:02,387 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:50:02,387 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:50:02,692 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:50:02,692 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:50:02,692 DEBUG --- stdout --- 2024-04-19 06:50:02,692 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:50:02,692 DEBUG --- stderr --- 2024-04-19 06:50:02,692 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-3000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-19 06:50:02] authn-users-3000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-19 06:50:02,693 INFO 2024-04-19 06:50:02,693 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-19 06:50:02,693 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:50:02,996 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:50:02,996 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:50:02,996 DEBUG --- stdout --- 2024-04-19 06:50:02,996 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:50:02,996 DEBUG --- stderr --- 2024-04-19 06:50:02,996 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/order.json { "name": "authn-users-3000", "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-3000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-3000", "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=3000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-19 06:50:02] authn-users-3000 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-19 06:50:02,998 INFO 2024-04-19 06:50:02,998 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-19 06:50:02,998 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 06:50:03,232 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 06:50:03,233 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:50:03,233 DEBUG --- stdout --- 2024-04-19 06:50:03,233 DEBUG WAITING 2024-04-19 06:50:03,233 DEBUG --- stderr --- 2024-04-19 06:50:03,233 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-19 06:50:03] authn-users-3000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-19 06:50:03,234 INFO 2024-04-19 06:50:03,234 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-19 06:50:03,234 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:50:03,593 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:50:03,594 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:50:03,594 DEBUG --- stdout --- 2024-04-19 06:50:03,595 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:50:03,595 DEBUG --- stderr --- 2024-04-19 06:50:03,595 DEBUG 2024-04-19 06:50:03,595 INFO 2024-04-19 06:50:03,595 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-3000/order.json overseer-0-5c67bf94db-77mh9:/results/orders/order.json 2024-04-19 06:50:03,596 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 06:50:05,037 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:50:05,037 DEBUG --- stdout --- 2024-04-19 06:50:05,037 DEBUG 2024-04-19 06:50:05,037 DEBUG --- stderr --- 2024-04-19 06:50:05,037 DEBUG Order has been successfully forwarded to overseer [2024-04-19 06:50:05] - INFO: Check if Gatling simulation started successfully 2024-04-19 06:50:05,038 INFO 2024-04-19 06:50:05,038 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-19 06:50:05,038 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:50:05,360 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:50:05,360 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:50:05,360 DEBUG --- stdout --- 2024-04-19 06:50:05,360 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:50:05,360 DEBUG --- stderr --- 2024-04-19 06:50:05,360 DEBUG 2024-04-19 06:50:05,361 INFO 2024-04-19 06:50:05,361 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 -- head -n 25 /results/orders/authn-users-3000/stdout.txt | grep "started..." 2024-04-19 06:50:05,361 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 06:50:06,134 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-19 06:50:11,913 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-19 06:50:17,692 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-19 06:50:23,458 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-19 06:50:23,458 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:50:23,458 DEBUG --- stdout --- 2024-04-19 06:50:23,458 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 07:50:08.111 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 07:50:14.229 [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"); ^ 07:50:14.233 [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"); ^ 07:50:16.595 [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-19 06:50:23,458 DEBUG --- stderr --- 2024-04-19 06:50:23,458 DEBUG ________________________________________________________________________________ [2024-04-19 06:50:23] authn-users-3000 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-19 07:52:33] authn-users-3000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-19 07:52:33,794 INFO 2024-04-19 07:52:33,795 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-19 07:52:33,795 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:34,148 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:34,148 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:34,148 DEBUG --- stdout --- 2024-04-19 07:52:34,148 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:34,148 DEBUG --- stderr --- 2024-04-19 07:52:34,148 DEBUG 2024-04-19 07:52:34,149 INFO 2024-04-19 07:52:34,149 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 -- rm -f /results/orders/order.json 2024-04-19 07:52:34,149 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 07:52:35,033 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:35,033 DEBUG --- stdout --- 2024-04-19 07:52:35,033 DEBUG 2024-04-19 07:52:35,034 DEBUG --- stderr --- 2024-04-19 07:52:35,034 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-19 07:52:35] authn-users-3000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-19 07:52:35] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/order.json 2024-04-19 07:52:35,034 INFO 2024-04-19 07:52:35,035 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-19 07:52:35,035 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:35,373 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:35,374 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:35,375 DEBUG --- stdout --- 2024-04-19 07:52:35,375 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:35,375 DEBUG --- stderr --- 2024-04-19 07:52:35,375 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 07:52:35,375 INFO 2024-04-19 07:52:35,375 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-19 07:52:35,375 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:35,700 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:35,701 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:35,701 DEBUG --- stdout --- 2024-04-19 07:52:35,701 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:35,701 DEBUG --- stderr --- 2024-04-19 07:52:35,701 DEBUG 2024-04-19 07:52:35,701 INFO 2024-04-19 07:52:35,701 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3000/rc.txt" ]; then echo "/results/orders/authn-users-3000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/rc.txt file found" 2024-04-19 07:52:35,701 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 07:52:36,548 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 07:52:36,548 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:36,548 DEBUG --- stdout --- 2024-04-19 07:52:36,548 DEBUG /results/orders/authn-users-3000/rc.txt file found 2024-04-19 07:52:36,548 DEBUG --- stderr --- 2024-04-19 07:52:36,548 DEBUG 2024-04-19 07:52:36,548 INFO 2024-04-19 07:52:36,548 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-19 07:52:36,548 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:36,875 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:36,875 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:36,875 DEBUG --- stdout --- 2024-04-19 07:52:36,875 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:36,875 DEBUG --- stderr --- 2024-04-19 07:52:36,875 DEBUG 2024-04-19 07:52:36,875 INFO 2024-04-19 07:52:36,875 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-3000/rc.txt 2024-04-19 07:52:37,691 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 07:52:37,691 DEBUG --- stdout --- 2024-04-19 07:52:37,691 DEBUG 4 /results/orders/authn-users-3000/rc.txt 2024-04-19 07:52:37,691 DEBUG --- stderr --- 2024-04-19 07:52:37,691 DEBUG 2024-04-19 07:52:37,691 INFO - Download file (size 4k) 2024-04-19 07:52:37,691 INFO 2024-04-19 07:52:37,691 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-19 07:52:37,691 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:37,989 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:37,990 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:37,990 DEBUG --- stdout --- 2024-04-19 07:52:37,990 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:37,990 DEBUG --- stderr --- 2024-04-19 07:52:37,990 DEBUG source : /results/orders/authn-users-3000/rc.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/rc.txt 2024-04-19 07:52:37,990 INFO 2024-04-19 07:52:37,990 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-19 07:52:37,990 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:38,291 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:38,291 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:38,291 DEBUG --- stdout --- 2024-04-19 07:52:38,291 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:38,291 DEBUG --- stderr --- 2024-04-19 07:52:38,291 DEBUG 2024-04-19 07:52:38,292 INFO 2024-04-19 07:52:38,292 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-3000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/rc.txt 2024-04-19 07:52:38,292 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 07:52:39,038 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:39,038 DEBUG --- stdout --- 2024-04-19 07:52:39,038 DEBUG tar: Removing leading `/' from member names 2024-04-19 07:52:39,038 DEBUG --- stderr --- 2024-04-19 07:52:39,038 DEBUG 2024-04-19 07:52:39,039 INFO 2024-04-19 07:52:39,039 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-19 07:52:39,039 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:39,374 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:39,374 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:39,374 DEBUG --- stdout --- 2024-04-19 07:52:39,374 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:39,374 DEBUG --- stderr --- 2024-04-19 07:52:39,374 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 07:52:39,375 INFO 2024-04-19 07:52:39,375 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-19 07:52:39,375 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:39,692 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:39,692 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:39,692 DEBUG --- stdout --- 2024-04-19 07:52:39,692 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:39,692 DEBUG --- stderr --- 2024-04-19 07:52:39,692 DEBUG 2024-04-19 07:52:39,693 INFO 2024-04-19 07:52:39,693 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3000/stderr.txt" ]; then echo "/results/orders/authn-users-3000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/stderr.txt file found" 2024-04-19 07:52:39,693 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 07:52:40,533 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 07:52:40,534 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:40,535 DEBUG --- stdout --- 2024-04-19 07:52:40,535 DEBUG /results/orders/authn-users-3000/stderr.txt file found 2024-04-19 07:52:40,535 DEBUG --- stderr --- 2024-04-19 07:52:40,535 DEBUG 2024-04-19 07:52:40,535 INFO 2024-04-19 07:52:40,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-19 07:52:40,535 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:40,839 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:40,839 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:40,839 DEBUG --- stdout --- 2024-04-19 07:52:40,839 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:40,839 DEBUG --- stderr --- 2024-04-19 07:52:40,839 DEBUG 2024-04-19 07:52:40,840 INFO 2024-04-19 07:52:40,840 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-3000/stderr.txt 2024-04-19 07:52:41,658 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 07:52:41,658 DEBUG --- stdout --- 2024-04-19 07:52:41,659 DEBUG 0 /results/orders/authn-users-3000/stderr.txt 2024-04-19 07:52:41,659 DEBUG --- stderr --- 2024-04-19 07:52:41,659 DEBUG 2024-04-19 07:52:41,659 INFO - Download file (size 0k) 2024-04-19 07:52:41,659 INFO 2024-04-19 07:52:41,659 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-19 07:52:41,659 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:42,020 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:42,020 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:42,020 DEBUG --- stdout --- 2024-04-19 07:52:42,020 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:42,020 DEBUG --- stderr --- 2024-04-19 07:52:42,020 DEBUG source : /results/orders/authn-users-3000/stderr.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/stderr.txt 2024-04-19 07:52:42,021 INFO 2024-04-19 07:52:42,021 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-19 07:52:42,021 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:42,335 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:42,336 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:42,336 DEBUG --- stdout --- 2024-04-19 07:52:42,336 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:42,336 DEBUG --- stderr --- 2024-04-19 07:52:42,336 DEBUG 2024-04-19 07:52:42,336 INFO 2024-04-19 07:52:42,336 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-3000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/stderr.txt 2024-04-19 07:52:42,336 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 07:52:43,091 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:43,091 DEBUG --- stdout --- 2024-04-19 07:52:43,091 DEBUG tar: Removing leading `/' from member names 2024-04-19 07:52:43,091 DEBUG --- stderr --- 2024-04-19 07:52:43,091 DEBUG 2024-04-19 07:52:43,092 INFO 2024-04-19 07:52:43,092 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-19 07:52:43,092 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:43,396 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:43,396 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:43,396 DEBUG --- stdout --- 2024-04-19 07:52:43,397 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:43,397 DEBUG --- stderr --- 2024-04-19 07:52:43,397 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 07:52:43,397 INFO 2024-04-19 07:52:43,397 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-19 07:52:43,397 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:43,693 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:43,694 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:43,694 DEBUG --- stdout --- 2024-04-19 07:52:43,694 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:43,694 DEBUG --- stderr --- 2024-04-19 07:52:43,694 DEBUG 2024-04-19 07:52:43,694 INFO 2024-04-19 07:52:43,694 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3000/stdout.txt" ]; then echo "/results/orders/authn-users-3000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/stdout.txt file found" 2024-04-19 07:52:43,695 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 07:52:44,559 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 07:52:44,559 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:44,559 DEBUG --- stdout --- 2024-04-19 07:52:44,559 DEBUG /results/orders/authn-users-3000/stdout.txt file found 2024-04-19 07:52:44,559 DEBUG --- stderr --- 2024-04-19 07:52:44,559 DEBUG 2024-04-19 07:52:44,559 INFO 2024-04-19 07:52:44,559 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-19 07:52:44,560 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:44,863 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:44,863 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:44,863 DEBUG --- stdout --- 2024-04-19 07:52:44,864 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:44,864 DEBUG --- stderr --- 2024-04-19 07:52:44,864 DEBUG 2024-04-19 07:52:44,864 INFO 2024-04-19 07:52:44,864 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-3000/stdout.txt 2024-04-19 07:52:45,682 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 07:52:45,683 DEBUG --- stdout --- 2024-04-19 07:52:45,684 DEBUG 508 /results/orders/authn-users-3000/stdout.txt 2024-04-19 07:52:45,684 DEBUG --- stderr --- 2024-04-19 07:52:45,684 DEBUG 2024-04-19 07:52:45,684 INFO - Download file (size 508k) 2024-04-19 07:52:45,684 INFO 2024-04-19 07:52:45,684 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-19 07:52:45,684 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:46,014 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:46,014 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:46,014 DEBUG --- stdout --- 2024-04-19 07:52:46,014 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:46,014 DEBUG --- stderr --- 2024-04-19 07:52:46,014 DEBUG source : /results/orders/authn-users-3000/stdout.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/stdout.txt 2024-04-19 07:52:46,015 INFO 2024-04-19 07:52:46,015 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 07:52:46,015 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:46,337 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:46,337 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:46,337 DEBUG --- stdout --- 2024-04-19 07:52:46,337 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:46,338 DEBUG --- stderr --- 2024-04-19 07:52:46,338 DEBUG 2024-04-19 07:52:46,338 INFO 2024-04-19 07:52:46,338 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-3000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/stdout.txt 2024-04-19 07:52:46,338 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 07:52:47,415 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:47,415 DEBUG --- stdout --- 2024-04-19 07:52:47,415 DEBUG tar: Removing leading `/' from member names 2024-04-19 07:52:47,415 DEBUG --- stderr --- 2024-04-19 07:52:47,415 DEBUG ________________________________________________________________________________ [2024-04-19 07:52:47] authn-users-3000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-19 07:52:47,416 INFO 2024-04-19 07:52:47,416 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-19 07:52:47,416 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:47,765 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:47,765 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:47,765 DEBUG --- stdout --- 2024-04-19 07:52:47,765 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:47,765 DEBUG --- stderr --- 2024-04-19 07:52:47,766 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 07:52:47,766 INFO 2024-04-19 07:52:47,766 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-19 07:52:47,766 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:48,177 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:48,177 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:48,177 DEBUG --- stdout --- 2024-04-19 07:52:48,177 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:48,177 DEBUG --- stderr --- 2024-04-19 07:52:48,177 DEBUG 2024-04-19 07:52:48,178 INFO 2024-04-19 07:52:48,178 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-3000/cmd.txt" ]; then echo "/results/orders/authn-users-3000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/cmd.txt file found" 2024-04-19 07:52:48,178 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 07:52:49,013 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 07:52:49,013 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:49,013 DEBUG --- stdout --- 2024-04-19 07:52:49,013 DEBUG /results/orders/authn-users-3000/cmd.txt file found 2024-04-19 07:52:49,013 DEBUG --- stderr --- 2024-04-19 07:52:49,013 DEBUG 2024-04-19 07:52:49,013 INFO 2024-04-19 07:52:49,014 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-19 07:52:49,014 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:49,328 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:49,328 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:49,328 DEBUG --- stdout --- 2024-04-19 07:52:49,328 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:49,328 DEBUG --- stderr --- 2024-04-19 07:52:49,329 DEBUG 2024-04-19 07:52:49,329 INFO 2024-04-19 07:52:49,329 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-3000/cmd.txt 2024-04-19 07:52:50,179 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 07:52:50,179 DEBUG --- stdout --- 2024-04-19 07:52:50,179 DEBUG 4 /results/orders/authn-users-3000/cmd.txt 2024-04-19 07:52:50,179 DEBUG --- stderr --- 2024-04-19 07:52:50,179 DEBUG 2024-04-19 07:52:50,179 INFO - Download file (size 4k) 2024-04-19 07:52:50,179 INFO 2024-04-19 07:52:50,180 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-19 07:52:50,180 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:50,485 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:50,485 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:50,485 DEBUG --- stdout --- 2024-04-19 07:52:50,485 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:50,485 DEBUG --- stderr --- 2024-04-19 07:52:50,485 DEBUG source : /results/orders/authn-users-3000/cmd.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/cmd.txt 2024-04-19 07:52:50,486 INFO 2024-04-19 07:52:50,486 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-19 07:52:50,486 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 07:52:50,789 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 07:52:50,791 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:50,791 DEBUG --- stdout --- 2024-04-19 07:52:50,791 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 07:52:50,791 DEBUG --- stderr --- 2024-04-19 07:52:50,791 DEBUG 2024-04-19 07:52:50,792 INFO 2024-04-19 07:52:50,792 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-3000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/cmd.txt 2024-04-19 07:52:50,792 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 07:52:51,587 INFO [loop_until]: OK (rc = 0) 2024-04-19 07:52:51,588 DEBUG --- stdout --- 2024-04-19 07:52:51,588 DEBUG tar: Removing leading `/' from member names 2024-04-19 07:52:51,588 DEBUG --- stderr --- 2024-04-19 07:52:51,588 DEBUG 2024-04-19 07:52:51,588 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/cmd.txt is small (1 lines), printing all lines: 2024-04-19 07:52:51,588 DEBUG ----- output ----- 2024-04-19 07:52:51,588 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-3000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-19 07:52:51,588 INFO ________________________________________________________________________________ [2024-04-19 07:52:51] authn-users-3000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-19 07:52:51,589 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/rc.txt is small (1 lines), printing all lines: 2024-04-19 07:52:51,589 DEBUG ----- output ----- 2024-04-19 07:52:51,589 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-19 07:52:51,589 INFO ________________________________________________________________________________ [2024-04-19 07:52:51] authn-users-3000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-19 07:52:51,591 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-3000/stdout.txt is 8754 lines): 2024-04-19 07:52:51,591 DEBUG ----- output ----- 2024-04-19 07:52:51,591 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-19 07:52:51,592 INFO 07:50:08.111 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-19 07:52:51,592 INFO 07:50:14.229 [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-19 07:52:51,592 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-19 07:52:51,592 INFO ^ 2024-04-19 07:52:51,592 INFO 07:50:14.233 [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-19 07:52:51,592 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-19 07:52:51,592 INFO ^ 2024-04-19 07:52:51,592 INFO 07:50:16.595 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-19 07:52:51,592 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-19 07:52:51,592 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 07:52:51,592 INFO 2024-04-19 07:52:51,592 INFO ================================================================================ 2024-04-19 07:52:51,592 INFO 2024-04-19 07:50:26 5s elapsed 2024-04-19 07:52:51,592 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,592 INFO > Global (OK=1250 KO=0 ) 2024-04-19 07:52:51,592 INFO > userRestLogin (OK=1250 KO=0 ) 2024-04-19 07:52:51,592 INFO 2024-04-19 07:52:51,592 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,592 INFO [- ] 0% 2024-04-19 07:52:51,592 INFO waiting: 10843632 / active: 6 / done: 1249 2024-04-19 07:52:51,592 INFO ================================================================================ 2024-04-19 07:52:51,592 INFO 2024-04-19 07:52:51,592 INFO 2024-04-19 07:52:51,592 INFO ================================================================================ 2024-04-19 07:52:51,593 INFO 2024-04-19 07:50:31 10s elapsed 2024-04-19 07:52:51,593 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,593 INFO > Global (OK=5000 KO=0 ) 2024-04-19 07:52:51,593 INFO > userRestLogin (OK=5000 KO=0 ) 2024-04-19 07:52:51,593 INFO 2024-04-19 07:52:51,593 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,593 INFO [- ] 0% 2024-04-19 07:52:51,593 INFO waiting: 10839878 / active: 9 / done: 5000 2024-04-19 07:52:51,593 INFO ================================================================================ 2024-04-19 07:52:51,593 INFO 2024-04-19 07:52:51,593 INFO 2024-04-19 07:52:51,593 INFO ================================================================================ 2024-04-19 07:52:51,593 INFO 2024-04-19 07:50:36 15s elapsed 2024-04-19 07:52:51,593 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,593 INFO > Global (OK=11251 KO=0 ) 2024-04-19 07:52:51,593 INFO > userRestLogin (OK=11251 KO=0 ) 2024-04-19 07:52:51,593 INFO 2024-04-19 07:52:51,593 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,593 INFO [- ] 0% 2024-04-19 07:52:51,593 INFO waiting: 10833623 / active: 13 / done: 11251 2024-04-19 07:52:51,593 INFO ================================================================================ 2024-04-19 07:52:51,594 INFO 2024-04-19 07:52:51,594 INFO 2024-04-19 07:52:51,594 INFO ================================================================================ 2024-04-19 07:52:51,594 INFO 2024-04-19 07:50:41 20s elapsed 2024-04-19 07:52:51,594 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,594 INFO > Global (OK=20000 KO=0 ) 2024-04-19 07:52:51,595 INFO > userRestLogin (OK=20000 KO=0 ) 2024-04-19 07:52:51,595 INFO 2024-04-19 07:52:51,595 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,595 INFO [- ] 0% 2024-04-19 07:52:51,595 INFO waiting: 10824870 / active: 18 / done: 19999 2024-04-19 07:52:51,595 INFO ================================================================================ 2024-04-19 07:52:51,595 INFO 2024-04-19 07:52:51,596 INFO 2024-04-19 07:52:51,600 INFO ================================================================================ 2024-04-19 07:52:51,601 INFO 2024-04-19 07:50:46 25s elapsed 2024-04-19 07:52:51,601 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,601 INFO > Global (OK=31250 KO=0 ) 2024-04-19 07:52:51,601 INFO > userRestLogin (OK=31250 KO=0 ) 2024-04-19 07:52:51,601 INFO 2024-04-19 07:52:51,601 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,601 INFO [- ] 0% 2024-04-19 07:52:51,601 INFO waiting: 10813614 / active: 23 / done: 31250 2024-04-19 07:52:51,601 INFO ================================================================================ 2024-04-19 07:52:51,601 INFO 2024-04-19 07:52:51,601 INFO 2024-04-19 07:52:51,601 INFO ================================================================================ 2024-04-19 07:52:51,601 INFO 2024-04-19 07:50:51 30s elapsed 2024-04-19 07:52:51,601 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,601 INFO > Global (OK=44928 KO=0 ) 2024-04-19 07:52:51,601 INFO > userRestLogin (OK=44928 KO=0 ) 2024-04-19 07:52:51,601 INFO 2024-04-19 07:52:51,601 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,602 INFO [- ] 0% 2024-04-19 07:52:51,602 INFO waiting: 10799927 / active: 32 / done: 44928 2024-04-19 07:52:51,602 INFO ================================================================================ 2024-04-19 07:52:51,602 INFO 2024-04-19 07:52:51,602 INFO 2024-04-19 07:52:51,602 INFO ================================================================================ 2024-04-19 07:52:51,602 INFO 2024-04-19 07:50:56 35s elapsed 2024-04-19 07:52:51,602 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,602 INFO > Global (OK=60017 KO=0 ) 2024-04-19 07:52:51,602 INFO > userRestLogin (OK=60017 KO=0 ) 2024-04-19 07:52:51,602 INFO 2024-04-19 07:52:51,602 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,602 INFO [- ] 0% 2024-04-19 07:52:51,602 INFO waiting: 10784835 / active: 36 / done: 60016 2024-04-19 07:52:51,602 INFO ================================================================================ 2024-04-19 07:52:51,602 INFO 2024-04-19 07:52:51,602 INFO 2024-04-19 07:52:51,602 INFO ================================================================================ 2024-04-19 07:52:51,602 INFO 2024-04-19 07:51:01 40s elapsed 2024-04-19 07:52:51,602 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,602 INFO > Global (OK=75083 KO=0 ) 2024-04-19 07:52:51,602 INFO [...] 2024-04-19 07:52:51,606 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,606 INFO > Global (OK=10769973 KO=0 ) 2024-04-19 07:52:51,606 INFO > userRestLogin (OK=10769973 KO=0 ) 2024-04-19 07:52:51,606 INFO 2024-04-19 07:52:51,607 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,607 INFO [#########################################################################-] 99% 2024-04-19 07:52:51,607 INFO waiting: 74872 / active: 43 / done: 10769972 2024-04-19 07:52:51,607 INFO ================================================================================ 2024-04-19 07:52:51,607 INFO 2024-04-19 07:52:51,607 INFO 2024-04-19 07:52:51,607 INFO ================================================================================ 2024-04-19 07:52:51,607 INFO 2024-04-19 08:50:31 3610s elapsed 2024-04-19 07:52:51,607 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,607 INFO > Global (OK=10785130 KO=0 ) 2024-04-19 07:52:51,607 INFO > userRestLogin (OK=10785130 KO=0 ) 2024-04-19 07:52:51,607 INFO 2024-04-19 07:52:51,607 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,607 INFO [#########################################################################-] 99% 2024-04-19 07:52:51,607 INFO waiting: 59722 / active: 35 / done: 10785130 2024-04-19 07:52:51,607 INFO ================================================================================ 2024-04-19 07:52:51,607 INFO 2024-04-19 07:52:51,607 INFO 2024-04-19 07:52:51,607 INFO ================================================================================ 2024-04-19 07:52:51,607 INFO 2024-04-19 08:50:36 3615s elapsed 2024-04-19 07:52:51,607 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,607 INFO > Global (OK=10800144 KO=0 ) 2024-04-19 07:52:51,607 INFO > userRestLogin (OK=10800144 KO=0 ) 2024-04-19 07:52:51,607 INFO 2024-04-19 07:52:51,607 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,608 INFO [#########################################################################-] 99% 2024-04-19 07:52:51,608 INFO waiting: 44711 / active: 32 / done: 10800144 2024-04-19 07:52:51,608 INFO ================================================================================ 2024-04-19 07:52:51,608 INFO 2024-04-19 07:52:51,608 INFO 2024-04-19 07:52:51,608 INFO ================================================================================ 2024-04-19 07:52:51,608 INFO 2024-04-19 08:50:41 3620s elapsed 2024-04-19 07:52:51,608 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,608 INFO > Global (OK=10815021 KO=0 ) 2024-04-19 07:52:51,608 INFO > userRestLogin (OK=10815021 KO=0 ) 2024-04-19 07:52:51,608 INFO 2024-04-19 07:52:51,608 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,608 INFO [#########################################################################-] 99% 2024-04-19 07:52:51,608 INFO waiting: 29833 / active: 33 / done: 10815021 2024-04-19 07:52:51,608 INFO ================================================================================ 2024-04-19 07:52:51,608 INFO 2024-04-19 07:52:51,608 INFO 2024-04-19 07:52:51,608 INFO ================================================================================ 2024-04-19 07:52:51,608 INFO 2024-04-19 08:50:46 3625s elapsed 2024-04-19 07:52:51,608 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,608 INFO > Global (OK=10830093 KO=0 ) 2024-04-19 07:52:51,608 INFO > userRestLogin (OK=10830093 KO=0 ) 2024-04-19 07:52:51,608 INFO 2024-04-19 07:52:51,608 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,608 INFO [#########################################################################-] 99% 2024-04-19 07:52:51,608 INFO waiting: 14764 / active: 30 / done: 10830093 2024-04-19 07:52:51,609 INFO ================================================================================ 2024-04-19 07:52:51,609 INFO 2024-04-19 07:52:51,609 INFO 2024-04-19 07:52:51,609 INFO ================================================================================ 2024-04-19 07:52:51,609 INFO 2024-04-19 08:50:51 3630s elapsed 2024-04-19 07:52:51,609 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,609 INFO > Global (OK=10844886 KO=0 ) 2024-04-19 07:52:51,609 INFO > userRestLogin (OK=10844886 KO=0 ) 2024-04-19 07:52:51,609 INFO 2024-04-19 07:52:51,609 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,609 INFO [#########################################################################-] 99% 2024-04-19 07:52:51,609 INFO waiting: 0 / active: 2 / done: 10844885 2024-04-19 07:52:51,609 INFO ================================================================================ 2024-04-19 07:52:51,609 INFO 2024-04-19 07:52:51,609 INFO 2024-04-19 07:52:51,609 INFO ================================================================================ 2024-04-19 07:52:51,609 INFO 2024-04-19 08:50:51 3630s elapsed 2024-04-19 07:52:51,609 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 07:52:51,609 INFO > Global (OK=10844887 KO=0 ) 2024-04-19 07:52:51,609 INFO > userRestLogin (OK=10844887 KO=0 ) 2024-04-19 07:52:51,609 INFO 2024-04-19 07:52:51,609 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 07:52:51,609 INFO [##########################################################################]100% 2024-04-19 07:52:51,609 INFO waiting: 0 / active: 0 / done: 10844887 2024-04-19 07:52:51,609 INFO ================================================================================ 2024-04-19 07:52:51,610 INFO 2024-04-19 07:52:51,610 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-19 07:52:51,610 INFO 2024-04-19 07:52:51,610 INFO ---------------------- 2024-04-19 07:52:51,610 INFO [19/Apr/2024 08:50:51] Create tar file authn-users-3000.tar.gz 2024-04-19 07:52:51,610 INFO ---------------------- 2024-04-19 07:52:51,610 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-19 07:52:51,610 INFO RESULT_FOLDER=authn-users-3000 2024-04-19 07:52:51,610 INFO SIMULATION_PATH=authn-users-3000/loadtest-20240419065020616 2024-04-19 07:52:51,610 INFO PWD=/results/gatling/authn-users-3000/loadtest-20240419065020616 2024-04-19 07:52:51,610 INFO [cmd] tar -czvf /results/gatling//authn-users-3000.tar.gz --exclude=simulation.log * 2024-04-19 07:52:51,610 INFO /results/gatling 2024-04-19 07:52:51,610 INFO 2024-04-19 07:52:51,610 INFO ---------------------- 2024-04-19 07:52:51,610 INFO [19/Apr/2024 08:50:51] Update /results/gatling//latest 2024-04-19 07:52:51,610 INFO ---------------------- 2024-04-19 07:52:51,610 INFO 2024-04-19 07:52:51,610 INFO ---------------------- 2024-04-19 07:52:51,610 INFO [19/Apr/2024 08:50:51] Update /results/gatling//latest.tar.gz 2024-04-19 07:52:51,610 INFO ---------------------- 2024-04-19 07:52:51,610 INFO ________________________________________________________________________________ [2024-04-19 07:52:51] authn-users-3000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-19 07:52:51,611 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/stderr.txt is small (0 lines), printing all lines: 2024-04-19 07:52:51,611 DEBUG ----- output ----- 2024-04-19 07:52:51,611 DEBUG 2024-04-19 07:52:51,611 INFO ________________________________________________________________________________ [2024-04-19 07:52:51] authn-users-3000 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-19 07:52:51] authn-users-3000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-19 07:52:51] authn-users-3000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped