--Task-- name: authn-users-100 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: [] preceding_task: None options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 100, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-19 21:07:52] authn-users-100 pre : N/A ________________________________________________________________________________ [2024-04-19 21:07:52] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-19 21:07:52] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-100/stdout.txt Pod log : /results/orders/authn-users-100 2024-04-19 21:07:52,844 INFO 2024-04-19 21:07:52,844 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 21:07:52,844 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 21:07:53,165 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 21:07:53,165 INFO [loop_until]: OK (rc = 0) 2024-04-19 21:07:53,165 DEBUG --- stdout --- 2024-04-19 21:07:53,166 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 21:07:53,166 DEBUG --- stderr --- 2024-04-19 21:07:53,166 DEBUG [2024-04-19 21:07:53] - INFO: Get overseer pod resources requests memory 2024-04-19 21:07:53,166 INFO 2024-04-19 21:07:53,166 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-5c67bf94db-77mh9 --output jsonpath={.spec.containers[].resources.requests.memory} 2024-04-19 21:07:53,166 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 21:07:53,498 INFO [loop_until]: OK (rc = 0) 2024-04-19 21:07:53,498 DEBUG --- stdout --- 2024-04-19 21:07:53,498 DEBUG 4Gi 2024-04-19 21:07:53,498 DEBUG --- stderr --- 2024-04-19 21:07:53,498 DEBUG ________________________________________________________________________________ [2024-04-19 21:07:53] authn-users-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-19 21:07:53,499 INFO 2024-04-19 21:07:53,499 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 21:07:53,499 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 21:07:53,820 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 21:07:53,820 INFO [loop_until]: OK (rc = 0) 2024-04-19 21:07:53,820 DEBUG --- stdout --- 2024-04-19 21:07:53,820 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 21:07:53,820 DEBUG --- stderr --- 2024-04-19 21:07:53,820 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-100 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-19 21:07:53] authn-users-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-19 21:07:53,821 INFO 2024-04-19 21:07:53,821 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 21:07:53,821 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 21:07:54,134 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 21:07:54,134 INFO [loop_until]: OK (rc = 0) 2024-04-19 21:07:54,134 DEBUG --- stdout --- 2024-04-19 21:07:54,134 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 21:07:54,134 DEBUG --- stderr --- 2024-04-19 21:07:54,135 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/order.json { "name": "authn-users-100", "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-100 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-100", "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=100 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-19 21:07:54] authn-users-100 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 21:07:54,136 INFO 2024-04-19 21:07:54,136 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-19 21:07:54,136 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 21:07:54,432 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 21:07:54,432 INFO [loop_until]: OK (rc = 0) 2024-04-19 21:07:54,432 DEBUG --- stdout --- 2024-04-19 21:07:54,432 DEBUG WAITING 2024-04-19 21:07:54,433 DEBUG --- stderr --- 2024-04-19 21:07:54,433 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 25 0 --:--:-- --:--:-- --:--:-- 25 ________________________________________________________________________________ [2024-04-19 21:07:54] authn-users-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-19 21:07:54,433 INFO 2024-04-19 21:07:54,433 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 21:07:54,433 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 21:07:54,733 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 21:07:54,733 INFO [loop_until]: OK (rc = 0) 2024-04-19 21:07:54,733 DEBUG --- stdout --- 2024-04-19 21:07:54,733 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 21:07:54,733 DEBUG --- stderr --- 2024-04-19 21:07:54,733 DEBUG 2024-04-19 21:07:54,734 INFO 2024-04-19 21:07:54,734 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/01_authn-users-100/order.json overseer-0-5c67bf94db-77mh9:/results/orders/order.json 2024-04-19 21:07:54,734 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 21:07:56,114 INFO [loop_until]: OK (rc = 0) 2024-04-19 21:07:56,114 DEBUG --- stdout --- 2024-04-19 21:07:56,114 DEBUG 2024-04-19 21:07:56,114 DEBUG --- stderr --- 2024-04-19 21:07:56,114 DEBUG Order has been successfully forwarded to overseer [2024-04-19 21:07:56] - INFO: Check if Gatling simulation started successfully 2024-04-19 21:07:56,115 INFO 2024-04-19 21:07:56,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 21:07:56,115 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 21:07:56,437 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 21:07:56,437 INFO [loop_until]: OK (rc = 0) 2024-04-19 21:07:56,437 DEBUG --- stdout --- 2024-04-19 21:07:56,437 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 21:07:56,437 DEBUG --- stderr --- 2024-04-19 21:07:56,437 DEBUG 2024-04-19 21:07:56,438 INFO 2024-04-19 21:07:56,438 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-100/stdout.txt | grep "started..." 2024-04-19 21:07:56,438 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 21:07:57,223 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-19 21:08:02,978 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-19 21:08:08,769 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-19 21:08:14,540 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-19 21:08:14,540 INFO [loop_until]: OK (rc = 0) 2024-04-19 21:08:14,540 DEBUG --- stdout --- 2024-04-19 21:08:14,540 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 22:07:59.222 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 22:08:05.438 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 22:08:05.441 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 22:08:07.881 [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 21:08:14,540 DEBUG --- stderr --- 2024-04-19 21:08:14,540 DEBUG ________________________________________________________________________________ [2024-04-19 21:08:14] authn-users-100 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 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 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-19 22:10:25] authn-users-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-19 22:10:25,051 INFO 2024-04-19 22:10:25,051 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:25,051 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:25,376 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:25,376 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:25,376 DEBUG --- stdout --- 2024-04-19 22:10:25,376 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:25,376 DEBUG --- stderr --- 2024-04-19 22:10:25,376 DEBUG 2024-04-19 22:10:25,376 INFO 2024-04-19 22:10:25,376 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 22:10:25,376 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 22:10:26,169 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:26,170 DEBUG --- stdout --- 2024-04-19 22:10:26,170 DEBUG 2024-04-19 22:10:26,170 DEBUG --- stderr --- 2024-04-19 22:10:26,170 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-19 22:10:26] authn-users-100 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-19 22:10:26] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/order.json 2024-04-19 22:10:26,172 INFO 2024-04-19 22:10:26,172 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 22:10:26,172 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:26,499 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:26,499 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:26,499 DEBUG --- stdout --- 2024-04-19 22:10:26,499 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:26,500 DEBUG --- stderr --- 2024-04-19 22:10:26,500 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 22:10:26,500 INFO 2024-04-19 22:10:26,500 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:26,500 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:26,799 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:26,799 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:26,799 DEBUG --- stdout --- 2024-04-19 22:10:26,800 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:26,800 DEBUG --- stderr --- 2024-04-19 22:10:26,800 DEBUG 2024-04-19 22:10:26,800 INFO 2024-04-19 22:10:26,800 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-100/rc.txt" ]; then echo "/results/orders/authn-users-100/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-100/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/rc.txt file found" 2024-04-19 22:10:26,800 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 22:10:27,618 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 22:10:27,618 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:27,618 DEBUG --- stdout --- 2024-04-19 22:10:27,618 DEBUG /results/orders/authn-users-100/rc.txt file found 2024-04-19 22:10:27,619 DEBUG --- stderr --- 2024-04-19 22:10:27,619 DEBUG 2024-04-19 22:10:27,619 INFO 2024-04-19 22:10:27,619 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:27,619 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:27,941 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:27,941 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:27,941 DEBUG --- stdout --- 2024-04-19 22:10:27,942 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:27,942 DEBUG --- stderr --- 2024-04-19 22:10:27,942 DEBUG 2024-04-19 22:10:27,942 INFO 2024-04-19 22:10:27,942 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-100/rc.txt 2024-04-19 22:10:28,801 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 22:10:28,801 DEBUG --- stdout --- 2024-04-19 22:10:28,801 DEBUG 4 /results/orders/authn-users-100/rc.txt 2024-04-19 22:10:28,801 DEBUG --- stderr --- 2024-04-19 22:10:28,801 DEBUG 2024-04-19 22:10:28,801 INFO - Download file (size 4k) 2024-04-19 22:10:28,802 INFO 2024-04-19 22:10:28,802 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:28,802 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:29,123 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:29,124 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:29,124 DEBUG --- stdout --- 2024-04-19 22:10:29,124 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:29,124 DEBUG --- stderr --- 2024-04-19 22:10:29,124 DEBUG source : /results/orders/authn-users-100/rc.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-19 22:10:29,125 INFO 2024-04-19 22:10:29,125 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:29,125 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:29,428 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:29,429 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:29,429 DEBUG --- stdout --- 2024-04-19 22:10:29,429 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:29,429 DEBUG --- stderr --- 2024-04-19 22:10:29,429 DEBUG 2024-04-19 22:10:29,429 INFO 2024-04-19 22:10:29,429 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-100/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-19 22:10:29,429 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 22:10:30,228 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:30,229 DEBUG --- stdout --- 2024-04-19 22:10:30,229 DEBUG tar: Removing leading `/' from member names 2024-04-19 22:10:30,229 DEBUG --- stderr --- 2024-04-19 22:10:30,229 DEBUG 2024-04-19 22:10:30,229 INFO 2024-04-19 22:10:30,229 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:30,229 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:30,582 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:30,583 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:30,583 DEBUG --- stdout --- 2024-04-19 22:10:30,583 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:30,583 DEBUG --- stderr --- 2024-04-19 22:10:30,583 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 22:10:30,583 INFO 2024-04-19 22:10:30,583 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:30,583 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:30,902 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:30,902 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:30,902 DEBUG --- stdout --- 2024-04-19 22:10:30,903 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:30,903 DEBUG --- stderr --- 2024-04-19 22:10:30,903 DEBUG 2024-04-19 22:10:30,903 INFO 2024-04-19 22:10:30,903 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-100/stderr.txt" ]; then echo "/results/orders/authn-users-100/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-100/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/stderr.txt file found" 2024-04-19 22:10:30,903 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 22:10:31,740 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 22:10:31,741 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:31,741 DEBUG --- stdout --- 2024-04-19 22:10:31,741 DEBUG /results/orders/authn-users-100/stderr.txt file found 2024-04-19 22:10:31,741 DEBUG --- stderr --- 2024-04-19 22:10:31,741 DEBUG 2024-04-19 22:10:31,741 INFO 2024-04-19 22:10:31,742 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:31,742 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:32,083 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:32,083 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:32,083 DEBUG --- stdout --- 2024-04-19 22:10:32,083 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:32,083 DEBUG --- stderr --- 2024-04-19 22:10:32,083 DEBUG 2024-04-19 22:10:32,083 INFO 2024-04-19 22:10:32,084 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-100/stderr.txt 2024-04-19 22:10:32,948 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 22:10:32,948 DEBUG --- stdout --- 2024-04-19 22:10:32,948 DEBUG 0 /results/orders/authn-users-100/stderr.txt 2024-04-19 22:10:32,948 DEBUG --- stderr --- 2024-04-19 22:10:32,948 DEBUG 2024-04-19 22:10:32,948 INFO - Download file (size 0k) 2024-04-19 22:10:32,948 INFO 2024-04-19 22:10:32,948 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:32,949 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:33,250 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:33,251 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:33,251 DEBUG --- stdout --- 2024-04-19 22:10:33,251 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:33,251 DEBUG --- stderr --- 2024-04-19 22:10:33,251 DEBUG source : /results/orders/authn-users-100/stderr.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-19 22:10:33,251 INFO 2024-04-19 22:10:33,251 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 22:10:33,251 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:33,568 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:33,568 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:33,568 DEBUG --- stdout --- 2024-04-19 22:10:33,568 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:33,569 DEBUG --- stderr --- 2024-04-19 22:10:33,569 DEBUG 2024-04-19 22:10:33,569 INFO 2024-04-19 22:10:33,569 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-100/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-19 22:10:33,569 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 22:10:34,326 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:34,327 DEBUG --- stdout --- 2024-04-19 22:10:34,327 DEBUG tar: Removing leading `/' from member names 2024-04-19 22:10:34,327 DEBUG --- stderr --- 2024-04-19 22:10:34,327 DEBUG 2024-04-19 22:10:34,327 INFO 2024-04-19 22:10:34,327 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 22:10:34,327 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:34,643 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:34,643 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:34,643 DEBUG --- stdout --- 2024-04-19 22:10:34,643 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:34,643 DEBUG --- stderr --- 2024-04-19 22:10:34,643 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 22:10:34,644 INFO 2024-04-19 22:10:34,644 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:34,644 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:34,965 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:34,966 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:34,966 DEBUG --- stdout --- 2024-04-19 22:10:34,966 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:34,966 DEBUG --- stderr --- 2024-04-19 22:10:34,966 DEBUG 2024-04-19 22:10:34,966 INFO 2024-04-19 22:10:34,966 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-100/stdout.txt" ]; then echo "/results/orders/authn-users-100/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-100/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/stdout.txt file found" 2024-04-19 22:10:34,966 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 22:10:35,815 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 22:10:35,815 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:35,815 DEBUG --- stdout --- 2024-04-19 22:10:35,815 DEBUG /results/orders/authn-users-100/stdout.txt file found 2024-04-19 22:10:35,815 DEBUG --- stderr --- 2024-04-19 22:10:35,815 DEBUG 2024-04-19 22:10:35,816 INFO 2024-04-19 22:10:35,816 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:35,816 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:36,197 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:36,197 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:36,197 DEBUG --- stdout --- 2024-04-19 22:10:36,197 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:36,197 DEBUG --- stderr --- 2024-04-19 22:10:36,197 DEBUG 2024-04-19 22:10:36,198 INFO 2024-04-19 22:10:36,198 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-100/stdout.txt 2024-04-19 22:10:37,057 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 22:10:37,059 DEBUG --- stdout --- 2024-04-19 22:10:37,059 DEBUG 504 /results/orders/authn-users-100/stdout.txt 2024-04-19 22:10:37,059 DEBUG --- stderr --- 2024-04-19 22:10:37,059 DEBUG 2024-04-19 22:10:37,059 INFO - Download file (size 504k) 2024-04-19 22:10:37,060 INFO 2024-04-19 22:10:37,060 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 22:10:37,060 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:37,410 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:37,411 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:37,411 DEBUG --- stdout --- 2024-04-19 22:10:37,411 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:37,411 DEBUG --- stderr --- 2024-04-19 22:10:37,411 DEBUG source : /results/orders/authn-users-100/stdout.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-19 22:10:37,412 INFO 2024-04-19 22:10:37,412 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:37,412 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:37,752 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:37,753 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:37,753 DEBUG --- stdout --- 2024-04-19 22:10:37,753 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:37,753 DEBUG --- stderr --- 2024-04-19 22:10:37,753 DEBUG 2024-04-19 22:10:37,754 INFO 2024-04-19 22:10:37,754 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-100/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-19 22:10:37,754 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 22:10:38,871 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:38,871 DEBUG --- stdout --- 2024-04-19 22:10:38,871 DEBUG tar: Removing leading `/' from member names 2024-04-19 22:10:38,871 DEBUG --- stderr --- 2024-04-19 22:10:38,871 DEBUG ________________________________________________________________________________ [2024-04-19 22:10:38] authn-users-100 show_cmd : Display order command ________________________________________________________________________________ 2024-04-19 22:10:38,872 INFO 2024-04-19 22:10:38,872 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:38,872 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:39,191 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:39,191 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:39,191 DEBUG --- stdout --- 2024-04-19 22:10:39,191 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:39,191 DEBUG --- stderr --- 2024-04-19 22:10:39,191 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 22:10:39,192 INFO 2024-04-19 22:10:39,192 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:39,192 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:39,507 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:39,507 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:39,507 DEBUG --- stdout --- 2024-04-19 22:10:39,507 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:39,507 DEBUG --- stderr --- 2024-04-19 22:10:39,507 DEBUG 2024-04-19 22:10:39,508 INFO 2024-04-19 22:10:39,508 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-100/cmd.txt" ]; then echo "/results/orders/authn-users-100/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-100/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/cmd.txt file found" 2024-04-19 22:10:39,508 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 22:10:40,326 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 22:10:40,326 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:40,326 DEBUG --- stdout --- 2024-04-19 22:10:40,326 DEBUG /results/orders/authn-users-100/cmd.txt file found 2024-04-19 22:10:40,326 DEBUG --- stderr --- 2024-04-19 22:10:40,327 DEBUG 2024-04-19 22:10:40,327 INFO 2024-04-19 22:10:40,327 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 22:10:40,327 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:40,628 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:40,628 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:40,628 DEBUG --- stdout --- 2024-04-19 22:10:40,628 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:40,628 DEBUG --- stderr --- 2024-04-19 22:10:40,628 DEBUG 2024-04-19 22:10:40,629 INFO 2024-04-19 22:10:40,629 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-100/cmd.txt 2024-04-19 22:10:41,447 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 22:10:41,447 DEBUG --- stdout --- 2024-04-19 22:10:41,448 DEBUG 4 /results/orders/authn-users-100/cmd.txt 2024-04-19 22:10:41,448 DEBUG --- stderr --- 2024-04-19 22:10:41,448 DEBUG 2024-04-19 22:10:41,448 INFO - Download file (size 4k) 2024-04-19 22:10:41,448 INFO 2024-04-19 22:10:41,448 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:41,448 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:41,780 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:41,780 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:41,780 DEBUG --- stdout --- 2024-04-19 22:10:41,780 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:41,780 DEBUG --- stderr --- 2024-04-19 22:10:41,780 DEBUG source : /results/orders/authn-users-100/cmd.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-19 22:10:41,781 INFO 2024-04-19 22:10:41,781 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:41,781 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 22:10:42,142 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 22:10:42,142 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:42,144 DEBUG --- stdout --- 2024-04-19 22:10:42,144 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 22:10:42,144 DEBUG --- stderr --- 2024-04-19 22:10:42,144 DEBUG 2024-04-19 22:10:42,144 INFO 2024-04-19 22:10:42,144 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-100/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-19 22:10:42,144 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 22:10:42,895 INFO [loop_until]: OK (rc = 0) 2024-04-19 22:10:42,895 DEBUG --- stdout --- 2024-04-19 22:10:42,895 DEBUG tar: Removing leading `/' from member names 2024-04-19 22:10:42,895 DEBUG --- stderr --- 2024-04-19 22:10:42,895 DEBUG 2024-04-19 22:10:42,896 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt is small (1 lines), printing all lines: 2024-04-19 22:10:42,896 DEBUG ----- output ----- 2024-04-19 22:10:42,896 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-100 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-19 22:10:42,896 INFO ________________________________________________________________________________ [2024-04-19 22:10:42] authn-users-100 show_rc : Display rc order ________________________________________________________________________________ 2024-04-19 22:10:42,896 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt is small (1 lines), printing all lines: 2024-04-19 22:10:42,896 DEBUG ----- output ----- 2024-04-19 22:10:42,896 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-19 22:10:42,896 INFO ________________________________________________________________________________ [2024-04-19 22:10:42] authn-users-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-19 22:10:42,898 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/01_authn-users-100/stdout.txt is 8754 lines): 2024-04-19 22:10:42,898 DEBUG ----- output ----- 2024-04-19 22:10:42,898 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-19 22:10:42,898 INFO 22:07:59.222 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-19 22:10:42,898 INFO 22:08:05.438 [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 22:10:42,898 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-19 22:10:42,898 INFO ^ 2024-04-19 22:10:42,898 INFO 22:08:05.441 [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 22:10:42,898 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-19 22:10:42,898 INFO ^ 2024-04-19 22:10:42,898 INFO 22:08:07.881 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-19 22:10:42,898 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-19 22:10:42,898 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 22:10:42,898 INFO 2024-04-19 22:10:42,898 INFO ================================================================================ 2024-04-19 22:10:42,898 INFO 2024-04-19 22:08:16 5s elapsed 2024-04-19 22:10:42,898 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,898 INFO > Global (OK=41 KO=0 ) 2024-04-19 22:10:42,898 INFO > userRestLogin (OK=41 KO=0 ) 2024-04-19 22:10:42,898 INFO 2024-04-19 22:10:42,898 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,898 INFO [- ] 0% 2024-04-19 22:10:42,898 INFO waiting: 361235 / active: 1 / done: 41 2024-04-19 22:10:42,898 INFO ================================================================================ 2024-04-19 22:10:42,898 INFO 2024-04-19 22:10:42,898 INFO 2024-04-19 22:10:42,899 INFO ================================================================================ 2024-04-19 22:10:42,899 INFO 2024-04-19 22:08:21 10s elapsed 2024-04-19 22:10:42,899 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,899 INFO > Global (OK=166 KO=0 ) 2024-04-19 22:10:42,899 INFO > userRestLogin (OK=166 KO=0 ) 2024-04-19 22:10:42,899 INFO 2024-04-19 22:10:42,899 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,899 INFO [- ] 0% 2024-04-19 22:10:42,899 INFO waiting: 361110 / active: 1 / done: 166 2024-04-19 22:10:42,899 INFO ================================================================================ 2024-04-19 22:10:42,899 INFO 2024-04-19 22:10:42,899 INFO 2024-04-19 22:10:42,899 INFO ================================================================================ 2024-04-19 22:10:42,899 INFO 2024-04-19 22:08:26 15s elapsed 2024-04-19 22:10:42,899 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,899 INFO > Global (OK=375 KO=0 ) 2024-04-19 22:10:42,899 INFO > userRestLogin (OK=375 KO=0 ) 2024-04-19 22:10:42,899 INFO 2024-04-19 22:10:42,899 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,899 INFO [- ] 0% 2024-04-19 22:10:42,899 INFO waiting: 360901 / active: 1 / done: 375 2024-04-19 22:10:42,899 INFO ================================================================================ 2024-04-19 22:10:42,899 INFO 2024-04-19 22:10:42,899 INFO 2024-04-19 22:10:42,899 INFO ================================================================================ 2024-04-19 22:10:42,899 INFO 2024-04-19 22:08:31 20s elapsed 2024-04-19 22:10:42,899 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,899 INFO > Global (OK=666 KO=0 ) 2024-04-19 22:10:42,899 INFO > userRestLogin (OK=666 KO=0 ) 2024-04-19 22:10:42,899 INFO 2024-04-19 22:10:42,899 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,899 INFO [- ] 0% 2024-04-19 22:10:42,899 INFO waiting: 360610 / active: 1 / done: 666 2024-04-19 22:10:42,899 INFO ================================================================================ 2024-04-19 22:10:42,899 INFO 2024-04-19 22:10:42,899 INFO 2024-04-19 22:10:42,899 INFO ================================================================================ 2024-04-19 22:10:42,899 INFO 2024-04-19 22:08:36 25s elapsed 2024-04-19 22:10:42,899 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,899 INFO > Global (OK=1041 KO=0 ) 2024-04-19 22:10:42,899 INFO > userRestLogin (OK=1041 KO=0 ) 2024-04-19 22:10:42,900 INFO 2024-04-19 22:10:42,900 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,900 INFO [- ] 0% 2024-04-19 22:10:42,900 INFO waiting: 360235 / active: 1 / done: 1041 2024-04-19 22:10:42,900 INFO ================================================================================ 2024-04-19 22:10:42,900 INFO 2024-04-19 22:10:42,900 INFO 2024-04-19 22:10:42,900 INFO ================================================================================ 2024-04-19 22:10:42,900 INFO 2024-04-19 22:08:41 30s elapsed 2024-04-19 22:10:42,900 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,900 INFO > Global (OK=1499 KO=0 ) 2024-04-19 22:10:42,900 INFO > userRestLogin (OK=1499 KO=0 ) 2024-04-19 22:10:42,900 INFO 2024-04-19 22:10:42,900 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,900 INFO [- ] 0% 2024-04-19 22:10:42,900 INFO waiting: 359776 / active: 2 / done: 1499 2024-04-19 22:10:42,900 INFO ================================================================================ 2024-04-19 22:10:42,900 INFO 2024-04-19 22:10:42,900 INFO 2024-04-19 22:10:42,900 INFO ================================================================================ 2024-04-19 22:10:42,900 INFO 2024-04-19 22:08:46 35s elapsed 2024-04-19 22:10:42,900 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,900 INFO > Global (OK=2009 KO=0 ) 2024-04-19 22:10:42,900 INFO > userRestLogin (OK=2009 KO=0 ) 2024-04-19 22:10:42,900 INFO 2024-04-19 22:10:42,900 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,900 INFO [- ] 0% 2024-04-19 22:10:42,900 INFO waiting: 359266 / active: 2 / done: 2009 2024-04-19 22:10:42,900 INFO ================================================================================ 2024-04-19 22:10:42,900 INFO 2024-04-19 22:10:42,900 INFO 2024-04-19 22:10:42,900 INFO ================================================================================ 2024-04-19 22:10:42,900 INFO 2024-04-19 22:08:51 40s elapsed 2024-04-19 22:10:42,900 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,900 INFO > Global (OK=2518 KO=0 ) 2024-04-19 22:10:42,900 INFO [...] 2024-04-19 22:10:42,903 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,903 INFO > Global (OK=358829 KO=0 ) 2024-04-19 22:10:42,903 INFO > userRestLogin (OK=358829 KO=0 ) 2024-04-19 22:10:42,903 INFO 2024-04-19 22:10:42,903 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,903 INFO [######################################################################### ] 99% 2024-04-19 22:10:42,903 INFO waiting: 2448 / active: 0 / done: 358829 2024-04-19 22:10:42,903 INFO ================================================================================ 2024-04-19 22:10:42,903 INFO 2024-04-19 22:10:42,903 INFO 2024-04-19 22:10:42,903 INFO ================================================================================ 2024-04-19 22:10:42,903 INFO 2024-04-19 23:08:21 3610s elapsed 2024-04-19 22:10:42,903 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,903 INFO > Global (OK=359329 KO=0 ) 2024-04-19 22:10:42,903 INFO > userRestLogin (OK=359329 KO=0 ) 2024-04-19 22:10:42,903 INFO 2024-04-19 22:10:42,903 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,903 INFO [######################################################################### ] 99% 2024-04-19 22:10:42,903 INFO waiting: 1948 / active: 0 / done: 359329 2024-04-19 22:10:42,903 INFO ================================================================================ 2024-04-19 22:10:42,903 INFO 2024-04-19 22:10:42,903 INFO 2024-04-19 22:10:42,903 INFO ================================================================================ 2024-04-19 22:10:42,903 INFO 2024-04-19 23:08:26 3615s elapsed 2024-04-19 22:10:42,903 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,903 INFO > Global (OK=359802 KO=0 ) 2024-04-19 22:10:42,903 INFO > userRestLogin (OK=359802 KO=0 ) 2024-04-19 22:10:42,903 INFO 2024-04-19 22:10:42,903 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,903 INFO [#########################################################################-] 99% 2024-04-19 22:10:42,903 INFO waiting: 1474 / active: 1 / done: 359802 2024-04-19 22:10:42,903 INFO ================================================================================ 2024-04-19 22:10:42,904 INFO 2024-04-19 22:10:42,904 INFO 2024-04-19 22:10:42,904 INFO ================================================================================ 2024-04-19 22:10:42,904 INFO 2024-04-19 23:08:31 3620s elapsed 2024-04-19 22:10:42,904 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,904 INFO > Global (OK=360277 KO=0 ) 2024-04-19 22:10:42,904 INFO > userRestLogin (OK=360277 KO=0 ) 2024-04-19 22:10:42,904 INFO 2024-04-19 22:10:42,904 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,904 INFO [######################################################################### ] 99% 2024-04-19 22:10:42,904 INFO waiting: 1000 / active: 0 / done: 360277 2024-04-19 22:10:42,904 INFO ================================================================================ 2024-04-19 22:10:42,904 INFO 2024-04-19 22:10:42,904 INFO 2024-04-19 22:10:42,904 INFO ================================================================================ 2024-04-19 22:10:42,904 INFO 2024-04-19 23:08:36 3625s elapsed 2024-04-19 22:10:42,904 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,904 INFO > Global (OK=360771 KO=0 ) 2024-04-19 22:10:42,904 INFO > userRestLogin (OK=360771 KO=0 ) 2024-04-19 22:10:42,904 INFO 2024-04-19 22:10:42,904 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,904 INFO [#########################################################################-] 99% 2024-04-19 22:10:42,904 INFO waiting: 505 / active: 1 / done: 360771 2024-04-19 22:10:42,904 INFO ================================================================================ 2024-04-19 22:10:42,904 INFO 2024-04-19 22:10:42,904 INFO 2024-04-19 22:10:42,904 INFO ================================================================================ 2024-04-19 22:10:42,904 INFO 2024-04-19 23:08:41 3630s elapsed 2024-04-19 22:10:42,904 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,904 INFO > Global (OK=361276 KO=0 ) 2024-04-19 22:10:42,904 INFO > userRestLogin (OK=361276 KO=0 ) 2024-04-19 22:10:42,904 INFO 2024-04-19 22:10:42,904 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,904 INFO [#########################################################################-] 99% 2024-04-19 22:10:42,904 INFO waiting: 0 / active: 1 / done: 361276 2024-04-19 22:10:42,904 INFO ================================================================================ 2024-04-19 22:10:42,904 INFO 2024-04-19 22:10:42,904 INFO 2024-04-19 22:10:42,904 INFO ================================================================================ 2024-04-19 22:10:42,904 INFO 2024-04-19 23:08:41 3630s elapsed 2024-04-19 22:10:42,904 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 22:10:42,905 INFO > Global (OK=361277 KO=0 ) 2024-04-19 22:10:42,905 INFO > userRestLogin (OK=361277 KO=0 ) 2024-04-19 22:10:42,905 INFO 2024-04-19 22:10:42,905 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 22:10:42,905 INFO [##########################################################################]100% 2024-04-19 22:10:42,905 INFO waiting: 0 / active: 0 / done: 361277 2024-04-19 22:10:42,905 INFO ================================================================================ 2024-04-19 22:10:42,905 INFO 2024-04-19 22:10:42,905 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-19 22:10:42,905 INFO 2024-04-19 22:10:42,905 INFO ---------------------- 2024-04-19 22:10:42,905 INFO [19/Apr/2024 23:08:41] Create tar file authn-users-100.tar.gz 2024-04-19 22:10:42,905 INFO ---------------------- 2024-04-19 22:10:42,905 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-19 22:10:42,905 INFO RESULT_FOLDER=authn-users-100 2024-04-19 22:10:42,905 INFO SIMULATION_PATH=authn-users-100/loadtest-20240419210810992 2024-04-19 22:10:42,905 INFO PWD=/results/gatling/authn-users-100/loadtest-20240419210810992 2024-04-19 22:10:42,905 INFO [cmd] tar -czvf /results/gatling//authn-users-100.tar.gz --exclude=simulation.log * 2024-04-19 22:10:42,905 INFO /results/gatling 2024-04-19 22:10:42,905 INFO 2024-04-19 22:10:42,905 INFO ---------------------- 2024-04-19 22:10:42,905 INFO [19/Apr/2024 23:08:41] Update /results/gatling//latest 2024-04-19 22:10:42,905 INFO ---------------------- 2024-04-19 22:10:42,905 INFO 2024-04-19 22:10:42,905 INFO ---------------------- 2024-04-19 22:10:42,905 INFO [19/Apr/2024 23:08:41] Update /results/gatling//latest.tar.gz 2024-04-19 22:10:42,905 INFO ---------------------- 2024-04-19 22:10:42,905 INFO ________________________________________________________________________________ [2024-04-19 22:10:42] authn-users-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-19 22:10:42,905 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt is small (0 lines), printing all lines: 2024-04-19 22:10:42,906 DEBUG ----- output ----- 2024-04-19 22:10:42,906 DEBUG 2024-04-19 22:10:42,906 INFO ________________________________________________________________________________ [2024-04-19 22:10:42] authn-users-100 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 22:10:42] authn-users-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-19 22:10:42] authn-users-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped