--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 22:10:43] authn-users-3000 pre : N/A ________________________________________________________________________________ [2024-04-19 22:10:43] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-19 22:10:43] - 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 22:10:43,785 INFO 2024-04-19 22:10:43,785 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 22:10:43,785 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:44,105 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:44,105 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:44,105 DEBUG --- stdout --- 2024-04-19 22:10:44,105 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:44,105 DEBUG --- stderr --- 2024-04-19 22:10:44,105 DEBUG ________________________________________________________________________________ [2024-04-19 22:10:44] authn-users-3000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-19 22:10:44,106 INFO 2024-04-19 22:10:44,106 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 22:10:44,106 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:44,417 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:44,417 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:44,417 DEBUG --- stdout --- 2024-04-19 22:10:44,418 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:44,418 DEBUG --- stderr --- 2024-04-19 22:10:44,418 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 22:10:44] authn-users-3000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-19 22:10:44,418 INFO 2024-04-19 22:10:44,418 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 22:10:44,418 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:44,719 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:44,719 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:44,719 DEBUG --- stdout --- 2024-04-19 22:10:44,719 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:44,719 DEBUG --- stderr --- 2024-04-19 22:10:44,719 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 22:10:44] 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 22:10:44,721 INFO 2024-04-19 22:10:44,721 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-19 22:10:44,721 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 22:10:44,950 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 22:10:44,950 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:44,950 DEBUG --- stdout --- 2024-04-19 22:10:44,950 DEBUG WAITING 2024-04-19 22:10:44,950 DEBUG --- stderr --- 2024-04-19 22:10:44,950 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 22:10:44] authn-users-3000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-19 22:10:44,951 INFO 2024-04-19 22:10: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-19 22:10:44,951 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:45,250 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:45,251 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:45,251 DEBUG --- stdout --- 2024-04-19 22:10:45,251 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:45,251 DEBUG --- stderr --- 2024-04-19 22:10:45,251 DEBUG 2024-04-19 22:10:45,251 INFO 2024-04-19 22:10:45,251 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 22:10:45,251 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 22:10:46,610 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:46,610 DEBUG --- stdout --- 2024-04-19 22:10:46,611 DEBUG 2024-04-19 22:10:46,611 DEBUG --- stderr --- 2024-04-19 22:10:46,611 DEBUG Order has been successfully forwarded to overseer [2024-04-19 22:10:46] - INFO: Check if Gatling simulation started successfully 2024-04-19 22:10:46,611 INFO 2024-04-19 22:10:46,611 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 22:10:46,611 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:46,910 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:46,910 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:46,910 DEBUG --- stdout --- 2024-04-19 22:10:46,910 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:46,910 DEBUG --- stderr --- 2024-04-19 22:10:46,910 DEBUG 2024-04-19 22:10:46,911 INFO 2024-04-19 22:10:46,911 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 22:10:46,911 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 22:10:47,660 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-19 22:10:53,413 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-19 22:10:59,200 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-19 22:11:04,983 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-19 22:11:04,983 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:11:04,983 DEBUG --- stdout --- 2024-04-19 22:11:04,983 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 23:10:49.745 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 23:10:55.925 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 23:10:55.928 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 23:10:58.492 [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 22:11:04,983 DEBUG --- stderr --- 2024-04-19 22:11:04,983 DEBUG ________________________________________________________________________________ [2024-04-19 22:11:04] 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 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 08s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 09s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 10s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-19 23:13:15] authn-users-3000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-19 23:13:15,902 INFO 2024-04-19 23:13:15,902 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 23:13:15,902 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:16,222 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:16,222 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:16,222 DEBUG --- stdout --- 2024-04-19 23:13:16,222 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:16,222 DEBUG --- stderr --- 2024-04-19 23:13:16,222 DEBUG 2024-04-19 23:13:16,223 INFO 2024-04-19 23:13:16,223 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 23:13:16,223 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 23:13:17,030 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:17,032 DEBUG --- stdout --- 2024-04-19 23:13:17,032 DEBUG 2024-04-19 23:13:17,032 DEBUG --- stderr --- 2024-04-19 23:13:17,032 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-19 23:13:17] authn-users-3000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-19 23:13:17] - 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 23:13:17,034 INFO 2024-04-19 23:13:17,034 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 23:13:17,034 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:17,344 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:17,344 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:17,344 DEBUG --- stdout --- 2024-04-19 23:13:17,344 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:17,344 DEBUG --- stderr --- 2024-04-19 23:13:17,344 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 23:13:17,344 INFO 2024-04-19 23:13:17,345 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 23:13:17,345 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:17,651 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:17,652 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:17,652 DEBUG --- stdout --- 2024-04-19 23:13:17,652 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:17,652 DEBUG --- stderr --- 2024-04-19 23:13:17,652 DEBUG 2024-04-19 23:13:17,652 INFO 2024-04-19 23:13:17,652 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 23:13:17,652 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 23:13:18,470 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 23:13:18,471 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:18,471 DEBUG --- stdout --- 2024-04-19 23:13:18,471 DEBUG /results/orders/authn-users-3000/rc.txt file found 2024-04-19 23:13:18,471 DEBUG --- stderr --- 2024-04-19 23:13:18,471 DEBUG 2024-04-19 23:13:18,471 INFO 2024-04-19 23:13:18,471 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 23:13:18,471 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:18,783 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:18,784 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:18,784 DEBUG --- stdout --- 2024-04-19 23:13:18,784 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:18,784 DEBUG --- stderr --- 2024-04-19 23:13:18,784 DEBUG 2024-04-19 23:13:18,784 INFO 2024-04-19 23:13:18,784 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 23:13:19,621 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 23:13:19,621 DEBUG --- stdout --- 2024-04-19 23:13:19,622 DEBUG 4 /results/orders/authn-users-3000/rc.txt 2024-04-19 23:13:19,622 DEBUG --- stderr --- 2024-04-19 23:13:19,622 DEBUG 2024-04-19 23:13:19,622 INFO - Download file (size 4k) 2024-04-19 23:13:19,622 INFO 2024-04-19 23:13:19,622 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 23:13:19,622 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:19,934 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:19,935 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:19,935 DEBUG --- stdout --- 2024-04-19 23:13:19,935 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:19,935 DEBUG --- stderr --- 2024-04-19 23:13:19,935 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 23:13:19,935 INFO 2024-04-19 23:13:19,935 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 23:13:19,935 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:20,251 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:20,251 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:20,251 DEBUG --- stdout --- 2024-04-19 23:13:20,251 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:20,251 DEBUG --- stderr --- 2024-04-19 23:13:20,251 DEBUG 2024-04-19 23:13:20,252 INFO 2024-04-19 23:13:20,252 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 23:13:20,252 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 23:13:21,074 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:21,078 DEBUG --- stdout --- 2024-04-19 23:13:21,079 DEBUG tar: Removing leading `/' from member names 2024-04-19 23:13:21,079 DEBUG --- stderr --- 2024-04-19 23:13:21,079 DEBUG 2024-04-19 23:13:21,081 INFO 2024-04-19 23:13:21,081 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 23:13:21,081 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:21,437 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:21,437 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:21,437 DEBUG --- stdout --- 2024-04-19 23:13:21,437 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:21,437 DEBUG --- stderr --- 2024-04-19 23:13:21,438 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 23:13:21,438 INFO 2024-04-19 23:13:21,438 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 23:13:21,438 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:21,770 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:21,771 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:21,771 DEBUG --- stdout --- 2024-04-19 23:13:21,771 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:21,771 DEBUG --- stderr --- 2024-04-19 23:13:21,771 DEBUG 2024-04-19 23:13:21,771 INFO 2024-04-19 23:13:21,772 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 23:13:21,772 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 23:13:22,603 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 23:13:22,604 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:22,604 DEBUG --- stdout --- 2024-04-19 23:13:22,605 DEBUG /results/orders/authn-users-3000/stderr.txt file found 2024-04-19 23:13:22,605 DEBUG --- stderr --- 2024-04-19 23:13:22,605 DEBUG 2024-04-19 23:13:22,605 INFO 2024-04-19 23:13:22,605 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 23:13:22,606 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:22,944 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:22,944 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:22,944 DEBUG --- stdout --- 2024-04-19 23:13:22,944 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:22,944 DEBUG --- stderr --- 2024-04-19 23:13:22,945 DEBUG 2024-04-19 23:13:22,945 INFO 2024-04-19 23:13:22,945 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 23:13:23,805 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 23:13:23,805 DEBUG --- stdout --- 2024-04-19 23:13:23,805 DEBUG 0 /results/orders/authn-users-3000/stderr.txt 2024-04-19 23:13:23,805 DEBUG --- stderr --- 2024-04-19 23:13:23,805 DEBUG 2024-04-19 23:13:23,805 INFO - Download file (size 0k) 2024-04-19 23:13:23,805 INFO 2024-04-19 23:13:23,805 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 23:13:23,805 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:24,114 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:24,115 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:24,115 DEBUG --- stdout --- 2024-04-19 23:13:24,115 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:24,115 DEBUG --- stderr --- 2024-04-19 23:13:24,115 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 23:13:24,115 INFO 2024-04-19 23:13:24,115 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 23:13:24,115 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:24,430 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:24,431 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:24,431 DEBUG --- stdout --- 2024-04-19 23:13:24,431 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:24,431 DEBUG --- stderr --- 2024-04-19 23:13:24,431 DEBUG 2024-04-19 23:13:24,431 INFO 2024-04-19 23:13:24,431 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 23:13:24,431 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 23:13:25,187 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:25,188 DEBUG --- stdout --- 2024-04-19 23:13:25,188 DEBUG tar: Removing leading `/' from member names 2024-04-19 23:13:25,188 DEBUG --- stderr --- 2024-04-19 23:13:25,188 DEBUG 2024-04-19 23:13:25,188 INFO 2024-04-19 23:13:25,188 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 23:13:25,188 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:25,487 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:25,488 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:25,488 DEBUG --- stdout --- 2024-04-19 23:13:25,488 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:25,488 DEBUG --- stderr --- 2024-04-19 23:13:25,488 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 23:13:25,488 INFO 2024-04-19 23:13:25,488 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 23:13:25,488 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:25,784 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:25,784 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:25,785 DEBUG --- stdout --- 2024-04-19 23:13:25,785 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:25,785 DEBUG --- stderr --- 2024-04-19 23:13:25,785 DEBUG 2024-04-19 23:13:25,785 INFO 2024-04-19 23:13:25,785 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 23:13:25,785 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 23:13:26,608 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 23:13:26,608 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:26,608 DEBUG --- stdout --- 2024-04-19 23:13:26,608 DEBUG /results/orders/authn-users-3000/stdout.txt file found 2024-04-19 23:13:26,608 DEBUG --- stderr --- 2024-04-19 23:13:26,608 DEBUG 2024-04-19 23:13:26,609 INFO 2024-04-19 23:13:26,609 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 23:13:26,609 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:26,957 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:26,957 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:26,957 DEBUG --- stdout --- 2024-04-19 23:13:26,957 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:26,957 DEBUG --- stderr --- 2024-04-19 23:13:26,957 DEBUG 2024-04-19 23:13:26,957 INFO 2024-04-19 23:13:26,957 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 23:13:27,784 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 23:13:27,786 DEBUG --- stdout --- 2024-04-19 23:13:27,786 DEBUG 508 /results/orders/authn-users-3000/stdout.txt 2024-04-19 23:13:27,786 DEBUG --- stderr --- 2024-04-19 23:13:27,786 DEBUG 2024-04-19 23:13:27,786 INFO - Download file (size 508k) 2024-04-19 23:13:27,786 INFO 2024-04-19 23:13:27,787 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 23:13:27,787 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:28,147 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:28,148 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:28,148 DEBUG --- stdout --- 2024-04-19 23:13:28,148 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:28,148 DEBUG --- stderr --- 2024-04-19 23:13:28,148 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 23:13:28,148 INFO 2024-04-19 23:13:28,148 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 23:13:28,149 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:28,502 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:28,502 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:28,502 DEBUG --- stdout --- 2024-04-19 23:13:28,502 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:28,502 DEBUG --- stderr --- 2024-04-19 23:13:28,502 DEBUG 2024-04-19 23:13:28,503 INFO 2024-04-19 23:13:28,503 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 23:13:28,503 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 23:13:29,652 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:29,653 DEBUG --- stdout --- 2024-04-19 23:13:29,653 DEBUG tar: Removing leading `/' from member names 2024-04-19 23:13:29,653 DEBUG --- stderr --- 2024-04-19 23:13:29,653 DEBUG ________________________________________________________________________________ [2024-04-19 23:13:29] authn-users-3000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-19 23:13:29,653 INFO 2024-04-19 23:13:29,654 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 23:13:29,654 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:29,965 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:29,965 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:29,965 DEBUG --- stdout --- 2024-04-19 23:13:29,965 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:29,965 DEBUG --- stderr --- 2024-04-19 23:13:29,965 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 23:13:29,966 INFO 2024-04-19 23:13:29,966 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 23:13:29,966 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:30,266 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:30,266 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:30,266 DEBUG --- stdout --- 2024-04-19 23:13:30,266 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:30,266 DEBUG --- stderr --- 2024-04-19 23:13:30,266 DEBUG 2024-04-19 23:13:30,267 INFO 2024-04-19 23:13:30,267 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 23:13:30,267 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 23:13:31,085 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 23:13:31,085 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:31,085 DEBUG --- stdout --- 2024-04-19 23:13:31,085 DEBUG /results/orders/authn-users-3000/cmd.txt file found 2024-04-19 23:13:31,085 DEBUG --- stderr --- 2024-04-19 23:13:31,085 DEBUG 2024-04-19 23:13:31,085 INFO 2024-04-19 23:13:31,085 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 23:13:31,086 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:31,397 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:31,397 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:31,397 DEBUG --- stdout --- 2024-04-19 23:13:31,397 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:31,397 DEBUG --- stderr --- 2024-04-19 23:13:31,397 DEBUG 2024-04-19 23:13:31,397 INFO 2024-04-19 23:13:31,397 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 23:13:32,219 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 23:13:32,219 DEBUG --- stdout --- 2024-04-19 23:13:32,219 DEBUG 4 /results/orders/authn-users-3000/cmd.txt 2024-04-19 23:13:32,219 DEBUG --- stderr --- 2024-04-19 23:13:32,219 DEBUG 2024-04-19 23:13:32,219 INFO - Download file (size 4k) 2024-04-19 23:13:32,220 INFO 2024-04-19 23:13:32,220 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 23:13:32,220 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:32,563 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:32,563 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:32,563 DEBUG --- stdout --- 2024-04-19 23:13:32,563 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:32,563 DEBUG --- stderr --- 2024-04-19 23:13:32,564 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 23:13:32,564 INFO 2024-04-19 23:13:32,564 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 23:13:32,564 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:32,915 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:32,915 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:32,915 DEBUG --- stdout --- 2024-04-19 23:13:32,915 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:32,915 DEBUG --- stderr --- 2024-04-19 23:13:32,915 DEBUG 2024-04-19 23:13:32,916 INFO 2024-04-19 23:13:32,916 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 23:13:32,916 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 23:13:33,650 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:33,650 DEBUG --- stdout --- 2024-04-19 23:13:33,651 DEBUG tar: Removing leading `/' from member names 2024-04-19 23:13:33,651 DEBUG --- stderr --- 2024-04-19 23:13:33,651 DEBUG 2024-04-19 23:13:33,651 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 23:13:33,651 DEBUG ----- output ----- 2024-04-19 23:13:33,651 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 23:13:33,651 INFO ________________________________________________________________________________ [2024-04-19 23:13:33] authn-users-3000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-19 23:13:33,651 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 23:13:33,651 DEBUG ----- output ----- 2024-04-19 23:13:33,651 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-19 23:13:33,652 INFO ________________________________________________________________________________ [2024-04-19 23:13:33] authn-users-3000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-19 23:13:33,653 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 23:13:33,653 DEBUG ----- output ----- 2024-04-19 23:13:33,653 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-19 23:13:33,653 INFO 23:10:49.745 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-19 23:13:33,653 INFO 23:10:55.925 [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 23:13:33,653 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-19 23:13:33,653 INFO ^ 2024-04-19 23:13:33,653 INFO 23:10:55.928 [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 23:13:33,653 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-19 23:13:33,653 INFO ^ 2024-04-19 23:13:33,653 INFO 23:10:58.492 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-19 23:13:33,653 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-19 23:13:33,653 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 23:13:33,653 INFO 2024-04-19 23:13:33,653 INFO ================================================================================ 2024-04-19 23:13:33,653 INFO 2024-04-19 23:11:08 5s elapsed 2024-04-19 23:13:33,653 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,653 INFO > Global (OK=1250 KO=0 ) 2024-04-19 23:13:33,654 INFO > userRestLogin (OK=1250 KO=0 ) 2024-04-19 23:13:33,654 INFO 2024-04-19 23:13:33,654 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,654 INFO [- ] 0% 2024-04-19 23:13:33,654 INFO waiting: 10842721 / active: 4 / done: 1250 2024-04-19 23:13:33,654 INFO ================================================================================ 2024-04-19 23:13:33,654 INFO 2024-04-19 23:13:33,654 INFO 2024-04-19 23:13:33,654 INFO ================================================================================ 2024-04-19 23:13:33,654 INFO 2024-04-19 23:11:13 10s elapsed 2024-04-19 23:13:33,654 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,654 INFO > Global (OK=4998 KO=0 ) 2024-04-19 23:13:33,654 INFO > userRestLogin (OK=4998 KO=0 ) 2024-04-19 23:13:33,654 INFO 2024-04-19 23:13:33,654 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,654 INFO [- ] 0% 2024-04-19 23:13:33,654 INFO waiting: 10838968 / active: 9 / done: 4998 2024-04-19 23:13:33,654 INFO ================================================================================ 2024-04-19 23:13:33,654 INFO 2024-04-19 23:13:33,654 INFO 2024-04-19 23:13:33,654 INFO ================================================================================ 2024-04-19 23:13:33,654 INFO 2024-04-19 23:11:18 15s elapsed 2024-04-19 23:13:33,654 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,654 INFO > Global (OK=11249 KO=0 ) 2024-04-19 23:13:33,654 INFO > userRestLogin (OK=11249 KO=0 ) 2024-04-19 23:13:33,654 INFO 2024-04-19 23:13:33,654 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,654 INFO [- ] 0% 2024-04-19 23:13:33,654 INFO waiting: 10832712 / active: 14 / done: 11249 2024-04-19 23:13:33,654 INFO ================================================================================ 2024-04-19 23:13:33,654 INFO 2024-04-19 23:13:33,654 INFO 2024-04-19 23:13:33,654 INFO ================================================================================ 2024-04-19 23:13:33,654 INFO 2024-04-19 23:11:23 20s elapsed 2024-04-19 23:13:33,654 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,654 INFO > Global (OK=19995 KO=0 ) 2024-04-19 23:13:33,654 INFO > userRestLogin (OK=19995 KO=0 ) 2024-04-19 23:13:33,654 INFO 2024-04-19 23:13:33,654 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,655 INFO [- ] 0% 2024-04-19 23:13:33,655 INFO waiting: 10823962 / active: 18 / done: 19995 2024-04-19 23:13:33,655 INFO ================================================================================ 2024-04-19 23:13:33,655 INFO 2024-04-19 23:13:33,655 INFO 2024-04-19 23:13:33,655 INFO ================================================================================ 2024-04-19 23:13:33,655 INFO 2024-04-19 23:11:28 25s elapsed 2024-04-19 23:13:33,655 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,655 INFO > Global (OK=31242 KO=0 ) 2024-04-19 23:13:33,655 INFO > userRestLogin (OK=31242 KO=0 ) 2024-04-19 23:13:33,655 INFO 2024-04-19 23:13:33,655 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,655 INFO [- ] 0% 2024-04-19 23:13:33,655 INFO waiting: 10812707 / active: 26 / done: 31242 2024-04-19 23:13:33,655 INFO ================================================================================ 2024-04-19 23:13:33,655 INFO 2024-04-19 23:13:33,655 INFO 2024-04-19 23:13:33,655 INFO ================================================================================ 2024-04-19 23:13:33,655 INFO 2024-04-19 23:11:33 30s elapsed 2024-04-19 23:13:33,655 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,655 INFO > Global (OK=44928 KO=0 ) 2024-04-19 23:13:33,655 INFO > userRestLogin (OK=44928 KO=0 ) 2024-04-19 23:13:33,655 INFO 2024-04-19 23:13:33,655 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,655 INFO [- ] 0% 2024-04-19 23:13:33,655 INFO waiting: 10799015 / active: 32 / done: 44928 2024-04-19 23:13:33,655 INFO ================================================================================ 2024-04-19 23:13:33,655 INFO 2024-04-19 23:13:33,655 INFO 2024-04-19 23:13:33,655 INFO ================================================================================ 2024-04-19 23:13:33,655 INFO 2024-04-19 23:11:38 35s elapsed 2024-04-19 23:13:33,655 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,655 INFO > Global (OK=60078 KO=0 ) 2024-04-19 23:13:33,655 INFO > userRestLogin (OK=60078 KO=0 ) 2024-04-19 23:13:33,655 INFO 2024-04-19 23:13:33,655 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,655 INFO [- ] 0% 2024-04-19 23:13:33,655 INFO waiting: 10783847 / active: 50 / done: 60078 2024-04-19 23:13:33,655 INFO ================================================================================ 2024-04-19 23:13:33,655 INFO 2024-04-19 23:13:33,655 INFO 2024-04-19 23:13:33,656 INFO ================================================================================ 2024-04-19 23:13:33,656 INFO 2024-04-19 23:11:43 40s elapsed 2024-04-19 23:13:33,656 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,656 INFO > Global (OK=75064 KO=0 ) 2024-04-19 23:13:33,656 INFO [...] 2024-04-19 23:13:33,658 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,658 INFO > Global (OK=10768488 KO=0 ) 2024-04-19 23:13:33,658 INFO > userRestLogin (OK=10768488 KO=0 ) 2024-04-19 23:13:33,658 INFO 2024-04-19 23:13:33,658 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,658 INFO [#########################################################################-] 99% 2024-04-19 23:13:33,658 INFO waiting: 75457 / active: 30 / done: 10768488 2024-04-19 23:13:33,658 INFO ================================================================================ 2024-04-19 23:13:33,658 INFO 2024-04-19 23:13:33,658 INFO 2024-04-19 23:13:33,658 INFO ================================================================================ 2024-04-19 23:13:33,658 INFO 2024-04-20 00:11:13 3610s elapsed 2024-04-19 23:13:33,658 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,658 INFO > Global (OK=10783592 KO=0 ) 2024-04-19 23:13:33,658 INFO > userRestLogin (OK=10783592 KO=0 ) 2024-04-19 23:13:33,658 INFO 2024-04-19 23:13:33,658 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,658 INFO [#########################################################################-] 99% 2024-04-19 23:13:33,658 INFO waiting: 60352 / active: 31 / done: 10783592 2024-04-19 23:13:33,658 INFO ================================================================================ 2024-04-19 23:13:33,658 INFO 2024-04-19 23:13:33,658 INFO 2024-04-19 23:13:33,658 INFO ================================================================================ 2024-04-19 23:13:33,658 INFO 2024-04-20 00:11:18 3615s elapsed 2024-04-19 23:13:33,658 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,659 INFO > Global (OK=10798727 KO=0 ) 2024-04-19 23:13:33,659 INFO > userRestLogin (OK=10798727 KO=0 ) 2024-04-19 23:13:33,659 INFO 2024-04-19 23:13:33,659 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,659 INFO [#########################################################################-] 99% 2024-04-19 23:13:33,659 INFO waiting: 45210 / active: 38 / done: 10798727 2024-04-19 23:13:33,659 INFO ================================================================================ 2024-04-19 23:13:33,659 INFO 2024-04-19 23:13:33,659 INFO 2024-04-19 23:13:33,659 INFO ================================================================================ 2024-04-19 23:13:33,659 INFO 2024-04-20 00:11:23 3620s elapsed 2024-04-19 23:13:33,659 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,659 INFO > Global (OK=10813669 KO=0 ) 2024-04-19 23:13:33,659 INFO > userRestLogin (OK=10813669 KO=0 ) 2024-04-19 23:13:33,659 INFO 2024-04-19 23:13:33,659 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,659 INFO [#########################################################################-] 99% 2024-04-19 23:13:33,659 INFO waiting: 30272 / active: 34 / done: 10813669 2024-04-19 23:13:33,659 INFO ================================================================================ 2024-04-19 23:13:33,659 INFO 2024-04-19 23:13:33,659 INFO 2024-04-19 23:13:33,659 INFO ================================================================================ 2024-04-19 23:13:33,659 INFO 2024-04-20 00:11:28 3625s elapsed 2024-04-19 23:13:33,659 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,659 INFO > Global (OK=10828623 KO=0 ) 2024-04-19 23:13:33,659 INFO > userRestLogin (OK=10828623 KO=0 ) 2024-04-19 23:13:33,659 INFO 2024-04-19 23:13:33,659 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,659 INFO [#########################################################################-] 99% 2024-04-19 23:13:33,659 INFO waiting: 15316 / active: 36 / done: 10828623 2024-04-19 23:13:33,659 INFO ================================================================================ 2024-04-19 23:13:33,659 INFO 2024-04-19 23:13:33,659 INFO 2024-04-19 23:13:33,659 INFO ================================================================================ 2024-04-19 23:13:33,659 INFO 2024-04-20 00:11:33 3630s elapsed 2024-04-19 23:13:33,659 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,659 INFO > Global (OK=10843969 KO=0 ) 2024-04-19 23:13:33,659 INFO > userRestLogin (OK=10843969 KO=0 ) 2024-04-19 23:13:33,659 INFO 2024-04-19 23:13:33,659 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,659 INFO [#########################################################################-] 99% 2024-04-19 23:13:33,659 INFO waiting: 0 / active: 7 / done: 10843968 2024-04-19 23:13:33,660 INFO ================================================================================ 2024-04-19 23:13:33,660 INFO 2024-04-19 23:13:33,660 INFO 2024-04-19 23:13:33,660 INFO ================================================================================ 2024-04-19 23:13:33,660 INFO 2024-04-20 00:11:33 3630s elapsed 2024-04-19 23:13:33,660 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 23:13:33,660 INFO > Global (OK=10843975 KO=0 ) 2024-04-19 23:13:33,660 INFO > userRestLogin (OK=10843975 KO=0 ) 2024-04-19 23:13:33,660 INFO 2024-04-19 23:13:33,660 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 23:13:33,660 INFO [##########################################################################]100% 2024-04-19 23:13:33,660 INFO waiting: 0 / active: 0 / done: 10843975 2024-04-19 23:13:33,660 INFO ================================================================================ 2024-04-19 23:13:33,660 INFO 2024-04-19 23:13:33,660 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-19 23:13:33,660 INFO 2024-04-19 23:13:33,660 INFO ---------------------- 2024-04-19 23:13:33,660 INFO [20/Apr/2024 00:11:33] Create tar file authn-users-3000.tar.gz 2024-04-19 23:13:33,660 INFO ---------------------- 2024-04-19 23:13:33,660 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-19 23:13:33,660 INFO RESULT_FOLDER=authn-users-3000 2024-04-19 23:13:33,660 INFO SIMULATION_PATH=authn-users-3000/loadtest-20240419221102641 2024-04-19 23:13:33,660 INFO PWD=/results/gatling/authn-users-3000/loadtest-20240419221102641 2024-04-19 23:13:33,660 INFO [cmd] tar -czvf /results/gatling//authn-users-3000.tar.gz --exclude=simulation.log * 2024-04-19 23:13:33,660 INFO /results/gatling 2024-04-19 23:13:33,660 INFO 2024-04-19 23:13:33,660 INFO ---------------------- 2024-04-19 23:13:33,660 INFO [20/Apr/2024 00:11:33] Update /results/gatling//latest 2024-04-19 23:13:33,660 INFO ---------------------- 2024-04-19 23:13:33,660 INFO 2024-04-19 23:13:33,660 INFO ---------------------- 2024-04-19 23:13:33,660 INFO [20/Apr/2024 00:11:33] Update /results/gatling//latest.tar.gz 2024-04-19 23:13:33,660 INFO ---------------------- 2024-04-19 23:13:33,660 INFO ________________________________________________________________________________ [2024-04-19 23:13:33] authn-users-3000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-19 23:13:33,661 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 23:13:33,661 DEBUG ----- output ----- 2024-04-19 23:13:33,661 DEBUG 2024-04-19 23:13:33,661 INFO ________________________________________________________________________________ [2024-04-19 23:13:33] 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 23:13:33] 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 23:13:33] authn-users-3000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped