--Task-- name: authn-users-2000 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': 2000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-12 22:12:38] authn-users-2000 pre : N/A ________________________________________________________________________________ [2024-04-12 22:12:38] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-12 22:12:38] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-2000/stdout.txt Pod log : /results/orders/authn-users-2000 2024-04-12 22:12:38,545 INFO 2024-04-12 22:12:38,545 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-12 22:12:38,545 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:38,854 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:38,854 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:38,854 DEBUG --- stdout --- 2024-04-12 22:12:38,854 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:38,854 DEBUG --- stderr --- 2024-04-12 22:12:38,854 DEBUG ________________________________________________________________________________ [2024-04-12 22:12:38] authn-users-2000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-12 22:12:38,855 INFO 2024-04-12 22:12:38,855 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-12 22:12:38,855 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:39,157 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:39,157 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:39,157 DEBUG --- stdout --- 2024-04-12 22:12:39,158 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:39,158 DEBUG --- stderr --- 2024-04-12 22:12:39,158 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-2000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-12 22:12:39] authn-users-2000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-12 22:12:39,158 INFO 2024-04-12 22:12:39,158 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-12 22:12:39,158 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:39,468 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:39,468 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:39,468 DEBUG --- stdout --- 2024-04-12 22:12:39,468 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:39,468 DEBUG --- stderr --- 2024-04-12 22:12:39,468 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/order.json { "name": "authn-users-2000", "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-2000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-2000", "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=2000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-12 22:12:39] authn-users-2000 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-12 22:12:39,470 INFO 2024-04-12 22:12:39,470 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-12 22:12:39,470 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-12 22:12:39,702 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-12 22:12:39,702 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:39,702 DEBUG --- stdout --- 2024-04-12 22:12:39,702 DEBUG WAITING 2024-04-12 22:12:39,702 DEBUG --- stderr --- 2024-04-12 22:12:39,702 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-12 22:12:39] authn-users-2000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-12 22:12:39,703 INFO 2024-04-12 22:12:39,703 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-12 22:12:39,703 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:40,047 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:40,047 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:40,049 DEBUG --- stdout --- 2024-04-12 22:12:40,049 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:40,049 DEBUG --- stderr --- 2024-04-12 22:12:40,049 DEBUG 2024-04-12 22:12:40,050 INFO 2024-04-12 22:12:40,050 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-2000/order.json overseer-0-59d79dcbf-spbbl:/results/orders/order.json 2024-04-12 22:12:40,050 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 22:12:41,446 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:41,446 DEBUG --- stdout --- 2024-04-12 22:12:41,446 DEBUG 2024-04-12 22:12:41,446 DEBUG --- stderr --- 2024-04-12 22:12:41,446 DEBUG Order has been successfully forwarded to overseer [2024-04-12 22:12:41] - INFO: Check if Gatling simulation started successfully 2024-04-12 22:12:41,446 INFO 2024-04-12 22:12:41,446 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:41,447 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:41,774 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:41,774 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:41,774 DEBUG --- stdout --- 2024-04-12 22:12:41,774 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:41,774 DEBUG --- stderr --- 2024-04-12 22:12:41,774 DEBUG 2024-04-12 22:12:41,775 INFO 2024-04-12 22:12:41,775 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl -- head -n 25 /results/orders/authn-users-2000/stdout.txt | grep "started..." 2024-04-12 22:12:41,775 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-12 22:12:42,561 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-12 22:12:48,340 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-12 22:12:54,126 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-12 22:12:59,901 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-12 22:12:59,901 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:59,901 DEBUG --- stdout --- 2024-04-12 22:12:59,901 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 23:12:44.565 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 23:12:50.501 [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:12:50.504 [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:12:52.877 [WARN ] i.g.c.ZincCompiler$ - three warnings found Simulation com.forgerock.gatling.Loadtest started... 2024-04-12 22:12:59,901 DEBUG --- stderr --- 2024-04-12 22:12:59,901 DEBUG ________________________________________________________________________________ [2024-04-12 22:12:59] authn-users-2000 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry 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 04s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 05s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 06s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 07s, 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-12 23:15:10] authn-users-2000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-12 23:15:10,732 INFO 2024-04-12 23:15:10,733 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 23:15:10,733 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:11,075 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:11,075 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:11,075 DEBUG --- stdout --- 2024-04-12 23:15:11,075 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:11,075 DEBUG --- stderr --- 2024-04-12 23:15:11,075 DEBUG 2024-04-12 23:15:11,076 INFO 2024-04-12 23:15:11,076 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl -- rm -f /results/orders/order.json 2024-04-12 23:15:11,076 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 23:15:11,892 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:11,892 DEBUG --- stdout --- 2024-04-12 23:15:11,892 DEBUG 2024-04-12 23:15:11,892 DEBUG --- stderr --- 2024-04-12 23:15:11,892 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-12 23:15:11] authn-users-2000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-12 23:15:11] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/order.json 2024-04-12 23:15:11,893 INFO 2024-04-12 23:15:11,893 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-12 23:15:11,893 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:12,213 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:12,213 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:12,213 DEBUG --- stdout --- 2024-04-12 23:15:12,213 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:12,214 DEBUG --- stderr --- 2024-04-12 23:15:12,214 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-12 23:15:12,214 INFO 2024-04-12 23:15:12,214 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-12 23:15:12,214 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:12,541 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:12,541 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:12,541 DEBUG --- stdout --- 2024-04-12 23:15:12,541 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:12,541 DEBUG --- stderr --- 2024-04-12 23:15:12,541 DEBUG 2024-04-12 23:15:12,542 INFO 2024-04-12 23:15:12,542 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-2000/rc.txt" ]; then echo "/results/orders/authn-users-2000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-2000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-2000/rc.txt file found" 2024-04-12 23:15:12,542 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 23:15:13,387 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-12 23:15:13,388 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:13,388 DEBUG --- stdout --- 2024-04-12 23:15:13,388 DEBUG /results/orders/authn-users-2000/rc.txt file found 2024-04-12 23:15:13,388 DEBUG --- stderr --- 2024-04-12 23:15:13,388 DEBUG 2024-04-12 23:15:13,388 INFO 2024-04-12 23:15:13,388 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 23:15:13,388 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:13,715 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:13,715 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:13,715 DEBUG --- stdout --- 2024-04-12 23:15:13,715 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:13,715 DEBUG --- stderr --- 2024-04-12 23:15:13,715 DEBUG 2024-04-12 23:15:13,715 INFO 2024-04-12 23:15:13,716 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-2000/rc.txt 2024-04-12 23:15:14,587 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-12 23:15:14,588 DEBUG --- stdout --- 2024-04-12 23:15:14,588 DEBUG 4 /results/orders/authn-users-2000/rc.txt 2024-04-12 23:15:14,588 DEBUG --- stderr --- 2024-04-12 23:15:14,588 DEBUG 2024-04-12 23:15:14,588 INFO - Download file (size 4k) 2024-04-12 23:15:14,588 INFO 2024-04-12 23:15:14,588 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-12 23:15:14,588 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:14,955 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:14,955 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:14,956 DEBUG --- stdout --- 2024-04-12 23:15:14,956 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:14,956 DEBUG --- stderr --- 2024-04-12 23:15:14,956 DEBUG source : /results/orders/authn-users-2000/rc.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/rc.txt 2024-04-12 23:15:14,956 INFO 2024-04-12 23:15:14,956 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-12 23:15:14,956 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:15,323 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:15,323 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:15,323 DEBUG --- stdout --- 2024-04-12 23:15:15,323 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:15,323 DEBUG --- stderr --- 2024-04-12 23:15:15,324 DEBUG 2024-04-12 23:15:15,324 INFO 2024-04-12 23:15:15,324 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-2000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/rc.txt 2024-04-12 23:15:15,324 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-12 23:15:16,132 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:16,133 DEBUG --- stdout --- 2024-04-12 23:15:16,133 DEBUG tar: Removing leading `/' from member names 2024-04-12 23:15:16,133 DEBUG --- stderr --- 2024-04-12 23:15:16,133 DEBUG 2024-04-12 23:15:16,133 INFO 2024-04-12 23:15:16,133 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-12 23:15:16,133 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:16,436 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:16,437 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:16,437 DEBUG --- stdout --- 2024-04-12 23:15:16,437 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:16,437 DEBUG --- stderr --- 2024-04-12 23:15:16,437 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-12 23:15:16,437 INFO 2024-04-12 23:15:16,437 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-12 23:15:16,437 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:16,742 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:16,743 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:16,743 DEBUG --- stdout --- 2024-04-12 23:15:16,743 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:16,743 DEBUG --- stderr --- 2024-04-12 23:15:16,743 DEBUG 2024-04-12 23:15:16,743 INFO 2024-04-12 23:15:16,743 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-2000/stderr.txt" ]; then echo "/results/orders/authn-users-2000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-2000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-2000/stderr.txt file found" 2024-04-12 23:15:16,743 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 23:15:17,574 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-12 23:15:17,574 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:17,574 DEBUG --- stdout --- 2024-04-12 23:15:17,575 DEBUG /results/orders/authn-users-2000/stderr.txt file found 2024-04-12 23:15:17,575 DEBUG --- stderr --- 2024-04-12 23:15:17,575 DEBUG 2024-04-12 23:15:17,575 INFO 2024-04-12 23:15:17,575 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-12 23:15:17,575 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:17,894 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:17,895 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:17,895 DEBUG --- stdout --- 2024-04-12 23:15:17,895 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:17,895 DEBUG --- stderr --- 2024-04-12 23:15:17,895 DEBUG 2024-04-12 23:15:17,895 INFO 2024-04-12 23:15:17,895 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-2000/stderr.txt 2024-04-12 23:15:18,732 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-12 23:15:18,732 DEBUG --- stdout --- 2024-04-12 23:15:18,732 DEBUG 0 /results/orders/authn-users-2000/stderr.txt 2024-04-12 23:15:18,732 DEBUG --- stderr --- 2024-04-12 23:15:18,732 DEBUG 2024-04-12 23:15:18,732 INFO - Download file (size 0k) 2024-04-12 23:15:18,733 INFO 2024-04-12 23:15:18,733 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 23:15:18,733 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:19,109 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:19,110 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:19,110 DEBUG --- stdout --- 2024-04-12 23:15:19,110 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:19,110 DEBUG --- stderr --- 2024-04-12 23:15:19,110 DEBUG source : /results/orders/authn-users-2000/stderr.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/stderr.txt 2024-04-12 23:15:19,110 INFO 2024-04-12 23:15:19,110 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-12 23:15:19,110 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:19,424 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:19,424 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:19,425 DEBUG --- stdout --- 2024-04-12 23:15:19,425 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:19,425 DEBUG --- stderr --- 2024-04-12 23:15:19,425 DEBUG 2024-04-12 23:15:19,425 INFO 2024-04-12 23:15:19,425 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-2000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/stderr.txt 2024-04-12 23:15:19,425 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-12 23:15:20,216 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:20,216 DEBUG --- stdout --- 2024-04-12 23:15:20,216 DEBUG tar: Removing leading `/' from member names 2024-04-12 23:15:20,216 DEBUG --- stderr --- 2024-04-12 23:15:20,216 DEBUG 2024-04-12 23:15:20,217 INFO 2024-04-12 23:15:20,217 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-12 23:15:20,217 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:20,526 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:20,526 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:20,526 DEBUG --- stdout --- 2024-04-12 23:15:20,526 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:20,526 DEBUG --- stderr --- 2024-04-12 23:15:20,526 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-12 23:15:20,527 INFO 2024-04-12 23:15:20,527 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-12 23:15:20,527 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:20,841 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:20,842 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:20,842 DEBUG --- stdout --- 2024-04-12 23:15:20,842 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:20,842 DEBUG --- stderr --- 2024-04-12 23:15:20,842 DEBUG 2024-04-12 23:15:20,842 INFO 2024-04-12 23:15:20,842 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-2000/stdout.txt" ]; then echo "/results/orders/authn-users-2000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-2000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-2000/stdout.txt file found" 2024-04-12 23:15:20,842 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 23:15:21,678 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-12 23:15:21,679 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:21,679 DEBUG --- stdout --- 2024-04-12 23:15:21,679 DEBUG /results/orders/authn-users-2000/stdout.txt file found 2024-04-12 23:15:21,679 DEBUG --- stderr --- 2024-04-12 23:15:21,679 DEBUG 2024-04-12 23:15:21,679 INFO 2024-04-12 23:15:21,679 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-12 23:15:21,679 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:22,007 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:22,007 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:22,007 DEBUG --- stdout --- 2024-04-12 23:15:22,007 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:22,007 DEBUG --- stderr --- 2024-04-12 23:15:22,007 DEBUG 2024-04-12 23:15:22,007 INFO 2024-04-12 23:15:22,008 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-2000/stdout.txt 2024-04-12 23:15:22,846 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-12 23:15:22,846 DEBUG --- stdout --- 2024-04-12 23:15:22,846 DEBUG 508 /results/orders/authn-users-2000/stdout.txt 2024-04-12 23:15:22,846 DEBUG --- stderr --- 2024-04-12 23:15:22,846 DEBUG 2024-04-12 23:15:22,846 INFO - Download file (size 508k) 2024-04-12 23:15:22,847 INFO 2024-04-12 23:15:22,847 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-12 23:15:22,847 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:23,167 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:23,167 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:23,167 DEBUG --- stdout --- 2024-04-12 23:15:23,167 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:23,167 DEBUG --- stderr --- 2024-04-12 23:15:23,167 DEBUG source : /results/orders/authn-users-2000/stdout.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/stdout.txt 2024-04-12 23:15:23,168 INFO 2024-04-12 23:15:23,168 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-12 23:15:23,168 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:23,466 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:23,466 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:23,466 DEBUG --- stdout --- 2024-04-12 23:15:23,466 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:23,466 DEBUG --- stderr --- 2024-04-12 23:15:23,467 DEBUG 2024-04-12 23:15:23,467 INFO 2024-04-12 23:15:23,467 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-2000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/stdout.txt 2024-04-12 23:15:23,467 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-12 23:15:24,527 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:24,527 DEBUG --- stdout --- 2024-04-12 23:15:24,527 DEBUG tar: Removing leading `/' from member names 2024-04-12 23:15:24,527 DEBUG --- stderr --- 2024-04-12 23:15:24,527 DEBUG ________________________________________________________________________________ [2024-04-12 23:15:24] authn-users-2000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-12 23:15:24,528 INFO 2024-04-12 23:15:24,528 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-12 23:15:24,528 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:24,873 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:24,873 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:24,873 DEBUG --- stdout --- 2024-04-12 23:15:24,873 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:24,873 DEBUG --- stderr --- 2024-04-12 23:15:24,873 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-12 23:15:24,873 INFO 2024-04-12 23:15:24,873 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-12 23:15:24,873 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:25,193 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:25,193 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:25,193 DEBUG --- stdout --- 2024-04-12 23:15:25,193 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:25,193 DEBUG --- stderr --- 2024-04-12 23:15:25,193 DEBUG 2024-04-12 23:15:25,194 INFO 2024-04-12 23:15:25,194 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-2000/cmd.txt" ]; then echo "/results/orders/authn-users-2000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-2000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-2000/cmd.txt file found" 2024-04-12 23:15:25,194 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 23:15:26,025 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-12 23:15:26,025 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:26,025 DEBUG --- stdout --- 2024-04-12 23:15:26,025 DEBUG /results/orders/authn-users-2000/cmd.txt file found 2024-04-12 23:15:26,025 DEBUG --- stderr --- 2024-04-12 23:15:26,025 DEBUG 2024-04-12 23:15:26,026 INFO 2024-04-12 23:15:26,026 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-12 23:15:26,026 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:26,356 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:26,357 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:26,357 DEBUG --- stdout --- 2024-04-12 23:15:26,357 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:26,357 DEBUG --- stderr --- 2024-04-12 23:15:26,357 DEBUG 2024-04-12 23:15:26,357 INFO 2024-04-12 23:15:26,357 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-2000/cmd.txt 2024-04-12 23:15:27,192 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-12 23:15:27,192 DEBUG --- stdout --- 2024-04-12 23:15:27,192 DEBUG 4 /results/orders/authn-users-2000/cmd.txt 2024-04-12 23:15:27,192 DEBUG --- stderr --- 2024-04-12 23:15:27,192 DEBUG 2024-04-12 23:15:27,192 INFO - Download file (size 4k) 2024-04-12 23:15:27,193 INFO 2024-04-12 23:15:27,193 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-12 23:15:27,193 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:27,502 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:27,502 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:27,502 DEBUG --- stdout --- 2024-04-12 23:15:27,503 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:27,503 DEBUG --- stderr --- 2024-04-12 23:15:27,503 DEBUG source : /results/orders/authn-users-2000/cmd.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/cmd.txt 2024-04-12 23:15:27,503 INFO 2024-04-12 23:15:27,503 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-12 23:15:27,503 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:27,806 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:27,806 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:27,806 DEBUG --- stdout --- 2024-04-12 23:15:27,806 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:27,806 DEBUG --- stderr --- 2024-04-12 23:15:27,806 DEBUG 2024-04-12 23:15:27,807 INFO 2024-04-12 23:15:27,807 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-2000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/cmd.txt 2024-04-12 23:15:27,807 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-12 23:15:28,580 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:28,580 DEBUG --- stdout --- 2024-04-12 23:15:28,580 DEBUG tar: Removing leading `/' from member names 2024-04-12 23:15:28,580 DEBUG --- stderr --- 2024-04-12 23:15:28,580 DEBUG 2024-04-12 23:15:28,581 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/cmd.txt is small (1 lines), printing all lines: 2024-04-12 23:15:28,581 DEBUG ----- output ----- 2024-04-12 23:15:28,581 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-2000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-12 23:15:28,581 INFO ________________________________________________________________________________ [2024-04-12 23:15:28] authn-users-2000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-12 23:15:28,581 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/rc.txt is small (1 lines), printing all lines: 2024-04-12 23:15:28,581 DEBUG ----- output ----- 2024-04-12 23:15:28,581 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-12 23:15:28,581 INFO ________________________________________________________________________________ [2024-04-12 23:15:28] authn-users-2000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-12 23:15:28,582 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-2000/stdout.txt is 8741 lines): 2024-04-12 23:15:28,582 DEBUG ----- output ----- 2024-04-12 23:15:28,583 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-12 23:15:28,583 INFO 23:12:44.565 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-12 23:15:28,583 INFO 23:12:50.501 [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-12 23:15:28,583 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-12 23:15:28,583 INFO ^ 2024-04-12 23:15:28,583 INFO 23:12:50.504 [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-12 23:15:28,583 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-12 23:15:28,583 INFO ^ 2024-04-12 23:15:28,583 INFO 23:12:52.877 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-12 23:15:28,583 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-12 23:15:28,583 INFO 2024-04-12 23:15:28,583 INFO ================================================================================ 2024-04-12 23:15:28,583 INFO 2024-04-12 23:13:02 5s elapsed 2024-04-12 23:15:28,583 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,583 INFO > Global (OK=833 KO=0 ) 2024-04-12 23:15:28,583 INFO > userRestLogin (OK=833 KO=0 ) 2024-04-12 23:15:28,583 INFO 2024-04-12 23:15:28,583 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,583 INFO [- ] 0% 2024-04-12 23:15:28,583 INFO waiting: 7226154 / active: 4 / done: 833 2024-04-12 23:15:28,583 INFO ================================================================================ 2024-04-12 23:15:28,583 INFO 2024-04-12 23:15:28,583 INFO 2024-04-12 23:15:28,583 INFO ================================================================================ 2024-04-12 23:15:28,583 INFO 2024-04-12 23:13:07 10s elapsed 2024-04-12 23:15:28,583 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,583 INFO > Global (OK=3334 KO=0 ) 2024-04-12 23:15:28,583 INFO > userRestLogin (OK=3334 KO=0 ) 2024-04-12 23:15:28,583 INFO 2024-04-12 23:15:28,583 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,583 INFO [- ] 0% 2024-04-12 23:15:28,583 INFO waiting: 7223650 / active: 7 / done: 3334 2024-04-12 23:15:28,583 INFO ================================================================================ 2024-04-12 23:15:28,583 INFO 2024-04-12 23:15:28,583 INFO 2024-04-12 23:15:28,583 INFO ================================================================================ 2024-04-12 23:15:28,583 INFO 2024-04-12 23:13:12 15s elapsed 2024-04-12 23:15:28,583 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,583 INFO > Global (OK=7500 KO=0 ) 2024-04-12 23:15:28,583 INFO > userRestLogin (OK=7500 KO=0 ) 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,584 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,584 INFO [- ] 0% 2024-04-12 23:15:28,584 INFO waiting: 7219481 / active: 10 / done: 7500 2024-04-12 23:15:28,584 INFO ================================================================================ 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,584 INFO ================================================================================ 2024-04-12 23:15:28,584 INFO 2024-04-12 23:13:17 20s elapsed 2024-04-12 23:15:28,584 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,584 INFO > Global (OK=13333 KO=0 ) 2024-04-12 23:15:28,584 INFO > userRestLogin (OK=13333 KO=0 ) 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,584 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,584 INFO [- ] 0% 2024-04-12 23:15:28,584 INFO waiting: 7213644 / active: 14 / done: 13333 2024-04-12 23:15:28,584 INFO ================================================================================ 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,584 INFO ================================================================================ 2024-04-12 23:15:28,584 INFO 2024-04-12 23:13:22 25s elapsed 2024-04-12 23:15:28,584 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,584 INFO > Global (OK=20831 KO=0 ) 2024-04-12 23:15:28,584 INFO > userRestLogin (OK=20831 KO=0 ) 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,584 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,584 INFO [- ] 0% 2024-04-12 23:15:28,584 INFO waiting: 7206142 / active: 19 / done: 20830 2024-04-12 23:15:28,584 INFO ================================================================================ 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,584 INFO ================================================================================ 2024-04-12 23:15:28,584 INFO 2024-04-12 23:13:27 30s elapsed 2024-04-12 23:15:28,584 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,584 INFO > Global (OK=29949 KO=0 ) 2024-04-12 23:15:28,584 INFO > userRestLogin (OK=29949 KO=0 ) 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,584 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,584 INFO [- ] 0% 2024-04-12 23:15:28,584 INFO waiting: 7197010 / active: 32 / done: 29949 2024-04-12 23:15:28,584 INFO ================================================================================ 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,584 INFO 2024-04-12 23:15:28,585 INFO ================================================================================ 2024-04-12 23:15:28,585 INFO 2024-04-12 23:13:32 35s elapsed 2024-04-12 23:15:28,585 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,585 INFO > Global (OK=40085 KO=0 ) 2024-04-12 23:15:28,585 INFO > userRestLogin (OK=40085 KO=0 ) 2024-04-12 23:15:28,585 INFO 2024-04-12 23:15:28,585 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,585 INFO [- ] 0% 2024-04-12 23:15:28,585 INFO waiting: 7186890 / active: 16 / done: 40085 2024-04-12 23:15:28,585 INFO ================================================================================ 2024-04-12 23:15:28,585 INFO 2024-04-12 23:15:28,585 INFO 2024-04-12 23:15:28,585 INFO ================================================================================ 2024-04-12 23:15:28,585 INFO 2024-04-12 23:13:37 40s elapsed 2024-04-12 23:15:28,585 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,585 INFO > Global (OK=49986 KO=0 ) 2024-04-12 23:15:28,585 INFO > userRestLogin (OK=49986 KO=0 ) 2024-04-12 23:15:28,585 INFO [...] 2024-04-12 23:15:28,587 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,587 INFO > Global (OK=7167270 KO=0 ) 2024-04-12 23:15:28,587 INFO > userRestLogin (OK=7167270 KO=0 ) 2024-04-12 23:15:28,587 INFO 2024-04-12 23:15:28,587 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,587 INFO [#########################################################################-] 99% 2024-04-12 23:15:28,587 INFO waiting: 59702 / active: 20 / done: 7167269 2024-04-12 23:15:28,587 INFO ================================================================================ 2024-04-12 23:15:28,587 INFO 2024-04-12 23:15:28,587 INFO 2024-04-12 23:15:28,587 INFO ================================================================================ 2024-04-12 23:15:28,587 INFO 2024-04-13 00:13:02 3605s elapsed 2024-04-12 23:15:28,587 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,587 INFO > Global (OK=7177228 KO=0 ) 2024-04-12 23:15:28,588 INFO > userRestLogin (OK=7177228 KO=0 ) 2024-04-12 23:15:28,588 INFO 2024-04-12 23:15:28,588 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,588 INFO [#########################################################################-] 99% 2024-04-12 23:15:28,588 INFO waiting: 49737 / active: 26 / done: 7177228 2024-04-12 23:15:28,588 INFO ================================================================================ 2024-04-12 23:15:28,588 INFO 2024-04-12 23:15:28,588 INFO 2024-04-12 23:15:28,588 INFO ================================================================================ 2024-04-12 23:15:28,588 INFO 2024-04-13 00:13:07 3610s elapsed 2024-04-12 23:15:28,588 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,588 INFO > Global (OK=7187232 KO=0 ) 2024-04-12 23:15:28,588 INFO > userRestLogin (OK=7187232 KO=0 ) 2024-04-12 23:15:28,588 INFO 2024-04-12 23:15:28,588 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,588 INFO [#########################################################################-] 99% 2024-04-12 23:15:28,588 INFO waiting: 39720 / active: 40 / done: 7187231 2024-04-12 23:15:28,588 INFO ================================================================================ 2024-04-12 23:15:28,588 INFO 2024-04-12 23:15:28,588 INFO 2024-04-12 23:15:28,588 INFO ================================================================================ 2024-04-12 23:15:28,588 INFO 2024-04-13 00:13:12 3615s elapsed 2024-04-12 23:15:28,588 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,588 INFO > Global (OK=7197157 KO=0 ) 2024-04-12 23:15:28,588 INFO > userRestLogin (OK=7197157 KO=0 ) 2024-04-12 23:15:28,588 INFO 2024-04-12 23:15:28,588 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,588 INFO [#########################################################################-] 99% 2024-04-12 23:15:28,588 INFO waiting: 29808 / active: 26 / done: 7197157 2024-04-12 23:15:28,588 INFO ================================================================================ 2024-04-12 23:15:28,588 INFO 2024-04-12 23:15:28,588 INFO 2024-04-12 23:15:28,588 INFO ================================================================================ 2024-04-12 23:15:28,588 INFO 2024-04-13 00:13:17 3620s elapsed 2024-04-12 23:15:28,588 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,588 INFO > Global (OK=7206968 KO=0 ) 2024-04-12 23:15:28,588 INFO > userRestLogin (OK=7206968 KO=0 ) 2024-04-12 23:15:28,588 INFO 2024-04-12 23:15:28,588 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,588 INFO [#########################################################################-] 99% 2024-04-12 23:15:28,588 INFO waiting: 19991 / active: 32 / done: 7206968 2024-04-12 23:15:28,589 INFO ================================================================================ 2024-04-12 23:15:28,589 INFO 2024-04-12 23:15:28,589 INFO 2024-04-12 23:15:28,589 INFO ================================================================================ 2024-04-12 23:15:28,589 INFO 2024-04-13 00:13:22 3625s elapsed 2024-04-12 23:15:28,589 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,589 INFO > Global (OK=7216936 KO=0 ) 2024-04-12 23:15:28,589 INFO > userRestLogin (OK=7216936 KO=0 ) 2024-04-12 23:15:28,589 INFO 2024-04-12 23:15:28,589 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,589 INFO [#########################################################################-] 99% 2024-04-12 23:15:28,589 INFO waiting: 10022 / active: 33 / done: 7216936 2024-04-12 23:15:28,589 INFO ================================================================================ 2024-04-12 23:15:28,589 INFO 2024-04-12 23:15:28,589 INFO 2024-04-12 23:15:28,589 INFO ================================================================================ 2024-04-12 23:15:28,589 INFO 2024-04-13 00:13:27 3630s elapsed 2024-04-12 23:15:28,589 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 23:15:28,589 INFO > Global (OK=7226991 KO=0 ) 2024-04-12 23:15:28,589 INFO > userRestLogin (OK=7226991 KO=0 ) 2024-04-12 23:15:28,589 INFO 2024-04-12 23:15:28,589 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 23:15:28,589 INFO [##########################################################################]100% 2024-04-12 23:15:28,589 INFO waiting: 0 / active: 0 / done: 7226991 2024-04-12 23:15:28,589 INFO ================================================================================ 2024-04-12 23:15:28,589 INFO 2024-04-12 23:15:28,589 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-12 23:15:28,589 INFO 2024-04-12 23:15:28,589 INFO ---------------------- 2024-04-12 23:15:28,589 INFO [13/Apr/2024 00:13:27] Create tar file authn-users-2000.tar.gz 2024-04-12 23:15:28,589 INFO ---------------------- 2024-04-12 23:15:28,589 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-12 23:15:28,589 INFO RESULT_FOLDER=authn-users-2000 2024-04-12 23:15:28,589 INFO SIMULATION_PATH=authn-users-2000/loadtest-20240412221256546 2024-04-12 23:15:28,589 INFO PWD=/results/gatling/authn-users-2000/loadtest-20240412221256546 2024-04-12 23:15:28,589 INFO [cmd] tar -czvf /results/gatling//authn-users-2000.tar.gz --exclude=simulation.log * 2024-04-12 23:15:28,589 INFO /results/gatling 2024-04-12 23:15:28,589 INFO 2024-04-12 23:15:28,589 INFO ---------------------- 2024-04-12 23:15:28,589 INFO [13/Apr/2024 00:13:27] Update /results/gatling//latest 2024-04-12 23:15:28,589 INFO ---------------------- 2024-04-12 23:15:28,589 INFO 2024-04-12 23:15:28,590 INFO ---------------------- 2024-04-12 23:15:28,590 INFO [13/Apr/2024 00:13:27] Update /results/gatling//latest.tar.gz 2024-04-12 23:15:28,590 INFO ---------------------- 2024-04-12 23:15:28,590 INFO ________________________________________________________________________________ [2024-04-12 23:15:28] authn-users-2000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-12 23:15:28,590 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/stderr.txt is small (0 lines), printing all lines: 2024-04-12 23:15:28,590 DEBUG ----- output ----- 2024-04-12 23:15:28,590 DEBUG 2024-04-12 23:15:28,590 INFO ________________________________________________________________________________ [2024-04-12 23:15:28] authn-users-2000 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-12 23:15:28] authn-users-2000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-12 23:15:28] authn-users-2000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped