--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-21 22:13:21] authn-users-100 pre : N/A ________________________________________________________________________________ [2024-04-21 22:13:21] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-21 22:13:21] - 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-21 22:13:21,430 INFO 2024-04-21 22:13:21,430 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-21 22:13:21,430 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 22:13:21,814 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 22:13:21,815 INFO [loop_until]: OK (rc = 0) 2024-04-21 22:13:21,815 DEBUG --- stdout --- 2024-04-21 22:13:21,815 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 22:13:21,815 DEBUG --- stderr --- 2024-04-21 22:13:21,815 DEBUG [2024-04-21 22:13:21] - INFO: Get overseer pod resources requests memory 2024-04-21 22:13:21,815 INFO 2024-04-21 22:13:21,815 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-68c5d5b884-p97w5 --output jsonpath={.spec.containers[].resources.requests.memory} 2024-04-21 22:13:21,816 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-21 22:13:22,200 INFO [loop_until]: OK (rc = 0) 2024-04-21 22:13:22,200 DEBUG --- stdout --- 2024-04-21 22:13:22,201 DEBUG 8Gi 2024-04-21 22:13:22,201 DEBUG --- stderr --- 2024-04-21 22:13:22,201 DEBUG ________________________________________________________________________________ [2024-04-21 22:13:22] authn-users-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-21 22:13:22,202 INFO 2024-04-21 22:13:22,202 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-21 22:13:22,202 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 22:13:22,599 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 22:13:22,599 INFO [loop_until]: OK (rc = 0) 2024-04-21 22:13:22,599 DEBUG --- stdout --- 2024-04-21 22:13:22,599 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 22:13:22,599 DEBUG --- stderr --- 2024-04-21 22:13:22,599 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 "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-21 22:13:22] authn-users-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-21 22:13:22,600 INFO 2024-04-21 22:13:22,600 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-21 22:13:22,600 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 22:13:22,926 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 22:13:22,926 INFO [loop_until]: OK (rc = 0) 2024-04-21 22:13:22,926 DEBUG --- stdout --- 2024-04-21 22:13:22,926 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 22:13:22,926 DEBUG --- stderr --- 2024-04-21 22:13:22,926 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 \"-Xmx12G\" --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-21 22:13:22] 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-21 22:13:22,927 INFO 2024-04-21 22:13:22,927 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-21 22:13:22,927 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-21 22:13:23,219 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-21 22:13:23,220 INFO [loop_until]: OK (rc = 0) 2024-04-21 22:13:23,220 DEBUG --- stdout --- 2024-04-21 22:13:23,220 DEBUG WAITING 2024-04-21 22:13:23,220 DEBUG --- stderr --- 2024-04-21 22:13:23,220 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 26 0 --:--:-- --:--:-- --:--:-- 26 ________________________________________________________________________________ [2024-04-21 22:13:23] authn-users-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-21 22:13:23,221 INFO 2024-04-21 22:13:23,221 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-21 22:13:23,221 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 22:13:23,546 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 22:13:23,546 INFO [loop_until]: OK (rc = 0) 2024-04-21 22:13:23,546 DEBUG --- stdout --- 2024-04-21 22:13:23,546 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 22:13:23,546 DEBUG --- stderr --- 2024-04-21 22:13:23,546 DEBUG 2024-04-21 22:13:23,547 INFO 2024-04-21 22:13:23,547 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-68c5d5b884-p97w5:/results/orders/order.json 2024-04-21 22:13:23,547 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-21 22:13:24,966 INFO [loop_until]: OK (rc = 0) 2024-04-21 22:13:24,967 DEBUG --- stdout --- 2024-04-21 22:13:24,967 DEBUG 2024-04-21 22:13:24,967 DEBUG --- stderr --- 2024-04-21 22:13:24,967 DEBUG Order has been successfully forwarded to overseer [2024-04-21 22:13:24] - INFO: Check if Gatling simulation started successfully 2024-04-21 22:13:24,967 INFO 2024-04-21 22:13:24,967 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-21 22:13:24,967 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 22:13:25,293 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 22:13:25,294 INFO [loop_until]: OK (rc = 0) 2024-04-21 22:13:25,294 DEBUG --- stdout --- 2024-04-21 22:13:25,294 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 22:13:25,294 DEBUG --- stderr --- 2024-04-21 22:13:25,294 DEBUG 2024-04-21 22:13:25,294 INFO 2024-04-21 22:13:25,294 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 -- head -n 25 /results/orders/authn-users-100/stdout.txt | grep "started..." 2024-04-21 22:13:25,294 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-21 22:13:26,073 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-21 22:13:31,860 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-21 22:13:37,634 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-21 22:13:43,402 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-21 22:13:43,403 INFO [loop_until]: OK (rc = 0) 2024-04-21 22:13:43,403 DEBUG --- stdout --- 2024-04-21 22:13:43,403 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 23:13:27.930 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 23:13:33.985 [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:13:33.989 [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:13:36.319 [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-21 22:13:43,403 DEBUG --- stderr --- 2024-04-21 22:13:43,403 DEBUG ________________________________________________________________________________ [2024-04-21 22:13:43] 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 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-21 23:15:54] authn-users-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-21 23:15:54,402 INFO 2024-04-21 23:15:54,402 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-21 23:15:54,402 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:15:54,729 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:15:54,729 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:15:54,729 DEBUG --- stdout --- 2024-04-21 23:15:54,729 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:15:54,729 DEBUG --- stderr --- 2024-04-21 23:15:54,729 DEBUG 2024-04-21 23:15:54,730 INFO 2024-04-21 23:15:54,730 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 -- rm -f /results/orders/order.json 2024-04-21 23:15:54,730 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-21 23:15:55,533 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:15:55,534 DEBUG --- stdout --- 2024-04-21 23:15:55,534 DEBUG 2024-04-21 23:15:55,534 DEBUG --- stderr --- 2024-04-21 23:15:55,534 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-21 23:15:55] authn-users-100 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-21 23:15:55] - 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-21 23:15:55,535 INFO 2024-04-21 23:15:55,535 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-21 23:15:55,535 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:15:55,843 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:15:55,844 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:15:55,844 DEBUG --- stdout --- 2024-04-21 23:15:55,844 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:15:55,844 DEBUG --- stderr --- 2024-04-21 23:15:55,844 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-21 23:15:55,844 INFO 2024-04-21 23:15:55,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-21 23:15:55,844 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:15:56,152 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:15:56,152 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:15:56,152 DEBUG --- stdout --- 2024-04-21 23:15:56,152 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:15:56,152 DEBUG --- stderr --- 2024-04-21 23:15:56,152 DEBUG 2024-04-21 23:15:56,152 INFO 2024-04-21 23:15:56,153 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --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-21 23:15:56,153 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-21 23:15:56,982 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-21 23:15:56,990 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:15:56,990 DEBUG --- stdout --- 2024-04-21 23:15:56,991 DEBUG /results/orders/authn-users-100/rc.txt file found 2024-04-21 23:15:56,991 DEBUG --- stderr --- 2024-04-21 23:15:56,991 DEBUG 2024-04-21 23:15:56,992 INFO 2024-04-21 23:15:56,992 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-21 23:15:56,992 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:15:57,346 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:15:57,346 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:15:57,346 DEBUG --- stdout --- 2024-04-21 23:15:57,346 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:15:57,346 DEBUG --- stderr --- 2024-04-21 23:15:57,346 DEBUG 2024-04-21 23:15:57,347 INFO 2024-04-21 23:15:57,347 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-100/rc.txt 2024-04-21 23:15:58,195 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-21 23:15:58,195 DEBUG --- stdout --- 2024-04-21 23:15:58,195 DEBUG 4 /results/orders/authn-users-100/rc.txt 2024-04-21 23:15:58,195 DEBUG --- stderr --- 2024-04-21 23:15:58,195 DEBUG 2024-04-21 23:15:58,195 INFO - Download file (size 4k) 2024-04-21 23:15:58,196 INFO 2024-04-21 23:15:58,196 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-21 23:15:58,196 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:15:58,557 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:15:58,557 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:15:58,557 DEBUG --- stdout --- 2024-04-21 23:15:58,557 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:15:58,557 DEBUG --- stderr --- 2024-04-21 23:15:58,557 DEBUG source : /results/orders/authn-users-100/rc.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-21 23:15:58,557 INFO 2024-04-21 23:15:58,557 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-21 23:15:58,557 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:15:58,935 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:15:58,935 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:15:58,935 DEBUG --- stdout --- 2024-04-21 23:15:58,935 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:15:58,935 DEBUG --- stderr --- 2024-04-21 23:15:58,935 DEBUG 2024-04-21 23:15:58,935 INFO 2024-04-21 23:15:58,935 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/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-21 23:15:58,935 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-21 23:15:59,730 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:15:59,730 DEBUG --- stdout --- 2024-04-21 23:15:59,730 DEBUG tar: Removing leading `/' from member names 2024-04-21 23:15:59,730 DEBUG --- stderr --- 2024-04-21 23:15:59,730 DEBUG 2024-04-21 23:15:59,730 INFO 2024-04-21 23:15:59,731 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-21 23:15:59,731 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:00,040 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:00,041 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:00,041 DEBUG --- stdout --- 2024-04-21 23:16:00,041 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:00,041 DEBUG --- stderr --- 2024-04-21 23:16:00,041 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-21 23:16:00,041 INFO 2024-04-21 23:16:00,041 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-21 23:16:00,041 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:00,349 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:00,349 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:00,349 DEBUG --- stdout --- 2024-04-21 23:16:00,350 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:00,350 DEBUG --- stderr --- 2024-04-21 23:16:00,350 DEBUG 2024-04-21 23:16:00,350 INFO 2024-04-21 23:16:00,350 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --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-21 23:16:00,350 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-21 23:16:01,174 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-21 23:16:01,174 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:01,175 DEBUG --- stdout --- 2024-04-21 23:16:01,175 DEBUG /results/orders/authn-users-100/stderr.txt file found 2024-04-21 23:16:01,175 DEBUG --- stderr --- 2024-04-21 23:16:01,175 DEBUG 2024-04-21 23:16:01,175 INFO 2024-04-21 23:16:01,175 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-21 23:16:01,175 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:01,486 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:01,487 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:01,487 DEBUG --- stdout --- 2024-04-21 23:16:01,487 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:01,487 DEBUG --- stderr --- 2024-04-21 23:16:01,487 DEBUG 2024-04-21 23:16:01,487 INFO 2024-04-21 23:16:01,487 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-100/stderr.txt 2024-04-21 23:16:02,316 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-21 23:16:02,316 DEBUG --- stdout --- 2024-04-21 23:16:02,316 DEBUG 0 /results/orders/authn-users-100/stderr.txt 2024-04-21 23:16:02,316 DEBUG --- stderr --- 2024-04-21 23:16:02,316 DEBUG 2024-04-21 23:16:02,316 INFO - Download file (size 0k) 2024-04-21 23:16:02,317 INFO 2024-04-21 23:16:02,317 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-21 23:16:02,317 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:02,625 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:02,625 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:02,625 DEBUG --- stdout --- 2024-04-21 23:16:02,626 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:02,626 DEBUG --- stderr --- 2024-04-21 23:16:02,626 DEBUG source : /results/orders/authn-users-100/stderr.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-21 23:16:02,626 INFO 2024-04-21 23:16:02,626 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-21 23:16:02,626 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:02,938 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:02,938 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:02,938 DEBUG --- stdout --- 2024-04-21 23:16:02,938 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:02,938 DEBUG --- stderr --- 2024-04-21 23:16:02,938 DEBUG 2024-04-21 23:16:02,939 INFO 2024-04-21 23:16:02,939 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/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-21 23:16:02,939 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-21 23:16:03,734 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:03,734 DEBUG --- stdout --- 2024-04-21 23:16:03,734 DEBUG tar: Removing leading `/' from member names 2024-04-21 23:16:03,734 DEBUG --- stderr --- 2024-04-21 23:16:03,734 DEBUG 2024-04-21 23:16:03,735 INFO 2024-04-21 23:16:03,735 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-21 23:16:03,735 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:04,094 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:04,094 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:04,094 DEBUG --- stdout --- 2024-04-21 23:16:04,095 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:04,095 DEBUG --- stderr --- 2024-04-21 23:16:04,095 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-21 23:16:04,095 INFO 2024-04-21 23:16:04,095 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-21 23:16:04,095 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:04,410 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:04,410 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:04,410 DEBUG --- stdout --- 2024-04-21 23:16:04,410 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:04,410 DEBUG --- stderr --- 2024-04-21 23:16:04,410 DEBUG 2024-04-21 23:16:04,411 INFO 2024-04-21 23:16:04,411 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --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-21 23:16:04,411 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-21 23:16:05,260 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-21 23:16:05,262 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:05,262 DEBUG --- stdout --- 2024-04-21 23:16:05,262 DEBUG /results/orders/authn-users-100/stdout.txt file found 2024-04-21 23:16:05,262 DEBUG --- stderr --- 2024-04-21 23:16:05,262 DEBUG 2024-04-21 23:16:05,263 INFO 2024-04-21 23:16:05,263 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-21 23:16:05,263 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:05,581 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:05,581 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:05,581 DEBUG --- stdout --- 2024-04-21 23:16:05,581 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:05,581 DEBUG --- stderr --- 2024-04-21 23:16:05,581 DEBUG 2024-04-21 23:16:05,582 INFO 2024-04-21 23:16:05,582 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-100/stdout.txt 2024-04-21 23:16:06,413 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-21 23:16:06,413 DEBUG --- stdout --- 2024-04-21 23:16:06,414 DEBUG 504 /results/orders/authn-users-100/stdout.txt 2024-04-21 23:16:06,414 DEBUG --- stderr --- 2024-04-21 23:16:06,414 DEBUG 2024-04-21 23:16:06,414 INFO - Download file (size 504k) 2024-04-21 23:16:06,414 INFO 2024-04-21 23:16:06,414 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-21 23:16:06,414 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:06,726 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:06,726 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:06,726 DEBUG --- stdout --- 2024-04-21 23:16:06,726 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:06,726 DEBUG --- stderr --- 2024-04-21 23:16:06,726 DEBUG source : /results/orders/authn-users-100/stdout.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-21 23:16:06,727 INFO 2024-04-21 23:16:06,727 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-21 23:16:06,727 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:07,032 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:07,033 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:07,033 DEBUG --- stdout --- 2024-04-21 23:16:07,033 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:07,033 DEBUG --- stderr --- 2024-04-21 23:16:07,033 DEBUG 2024-04-21 23:16:07,033 INFO 2024-04-21 23:16:07,033 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/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-21 23:16:07,033 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-21 23:16:08,104 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:08,104 DEBUG --- stdout --- 2024-04-21 23:16:08,104 DEBUG tar: Removing leading `/' from member names 2024-04-21 23:16:08,104 DEBUG --- stderr --- 2024-04-21 23:16:08,104 DEBUG ________________________________________________________________________________ [2024-04-21 23:16:08] authn-users-100 show_cmd : Display order command ________________________________________________________________________________ 2024-04-21 23:16:08,105 INFO 2024-04-21 23:16:08,105 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-21 23:16:08,105 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:08,422 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:08,422 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:08,422 DEBUG --- stdout --- 2024-04-21 23:16:08,423 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:08,423 DEBUG --- stderr --- 2024-04-21 23:16:08,423 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-21 23:16:08,423 INFO 2024-04-21 23:16:08,423 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-21 23:16:08,423 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:08,833 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:08,834 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:08,834 DEBUG --- stdout --- 2024-04-21 23:16:08,834 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:08,834 DEBUG --- stderr --- 2024-04-21 23:16:08,834 DEBUG 2024-04-21 23:16:08,835 INFO 2024-04-21 23:16:08,835 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --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-21 23:16:08,835 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-21 23:16:09,727 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-21 23:16:09,727 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:09,727 DEBUG --- stdout --- 2024-04-21 23:16:09,728 DEBUG /results/orders/authn-users-100/cmd.txt file found 2024-04-21 23:16:09,728 DEBUG --- stderr --- 2024-04-21 23:16:09,728 DEBUG 2024-04-21 23:16:09,728 INFO 2024-04-21 23:16:09,728 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-21 23:16:09,728 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:10,042 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:10,043 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:10,043 DEBUG --- stdout --- 2024-04-21 23:16:10,043 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:10,043 DEBUG --- stderr --- 2024-04-21 23:16:10,043 DEBUG 2024-04-21 23:16:10,043 INFO 2024-04-21 23:16:10,043 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-100/cmd.txt 2024-04-21 23:16:10,933 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-21 23:16:10,934 DEBUG --- stdout --- 2024-04-21 23:16:10,934 DEBUG 4 /results/orders/authn-users-100/cmd.txt 2024-04-21 23:16:10,934 DEBUG --- stderr --- 2024-04-21 23:16:10,934 DEBUG 2024-04-21 23:16:10,934 INFO - Download file (size 4k) 2024-04-21 23:16:10,934 INFO 2024-04-21 23:16:10,934 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-21 23:16:10,934 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:11,255 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:11,255 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:11,255 DEBUG --- stdout --- 2024-04-21 23:16:11,255 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:11,255 DEBUG --- stderr --- 2024-04-21 23:16:11,255 DEBUG source : /results/orders/authn-users-100/cmd.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-21 23:16:11,256 INFO 2024-04-21 23:16:11,256 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-21 23:16:11,256 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:11,564 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:11,565 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:11,565 DEBUG --- stdout --- 2024-04-21 23:16:11,565 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:11,565 DEBUG --- stderr --- 2024-04-21 23:16:11,565 DEBUG 2024-04-21 23:16:11,565 INFO 2024-04-21 23:16:11,565 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/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-21 23:16:11,565 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-21 23:16:12,311 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:12,311 DEBUG --- stdout --- 2024-04-21 23:16:12,311 DEBUG tar: Removing leading `/' from member names 2024-04-21 23:16:12,311 DEBUG --- stderr --- 2024-04-21 23:16:12,311 DEBUG 2024-04-21 23:16:12,311 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-21 23:16:12,311 DEBUG ----- output ----- 2024-04-21 23:16:12,311 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 "-Xmx12G" --no-reports 2024-04-21 23:16:12,311 INFO ________________________________________________________________________________ [2024-04-21 23:16:12] authn-users-100 show_rc : Display rc order ________________________________________________________________________________ 2024-04-21 23:16:12,312 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-21 23:16:12,312 DEBUG ----- output ----- 2024-04-21 23:16:12,312 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-21 23:16:12,312 INFO ________________________________________________________________________________ [2024-04-21 23:16:12] authn-users-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-21 23:16:12,313 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 8742 lines): 2024-04-21 23:16:12,313 DEBUG ----- output ----- 2024-04-21 23:16:12,313 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-21 23:16:12,313 INFO 23:13:27.930 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-21 23:16:12,313 INFO 23:13:33.985 [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-21 23:16:12,313 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-21 23:16:12,313 INFO ^ 2024-04-21 23:16:12,313 INFO 23:13:33.989 [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-21 23:16:12,313 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-21 23:16:12,313 INFO ^ 2024-04-21 23:16:12,314 INFO 23:13:36.319 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-21 23:16:12,314 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-21 23:16:12,314 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-21 23:16:12,314 INFO 2024-04-21 23:16:12,314 INFO ================================================================================ 2024-04-21 23:16:12,314 INFO 2024-04-21 23:13:45 5s elapsed 2024-04-21 23:16:12,314 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,314 INFO > Global (OK=41 KO=0 ) 2024-04-21 23:16:12,314 INFO > userRestLogin (OK=41 KO=0 ) 2024-04-21 23:16:12,314 INFO 2024-04-21 23:16:12,314 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,314 INFO [- ] 0% 2024-04-21 23:16:12,314 INFO waiting: 360974 / active: 1 / done: 41 2024-04-21 23:16:12,314 INFO ================================================================================ 2024-04-21 23:16:12,314 INFO 2024-04-21 23:16:12,314 INFO 2024-04-21 23:16:12,314 INFO ================================================================================ 2024-04-21 23:16:12,314 INFO 2024-04-21 23:13:50 10s elapsed 2024-04-21 23:16:12,314 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,314 INFO > Global (OK=166 KO=0 ) 2024-04-21 23:16:12,314 INFO > userRestLogin (OK=166 KO=0 ) 2024-04-21 23:16:12,314 INFO 2024-04-21 23:16:12,314 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,314 INFO [- ] 0% 2024-04-21 23:16:12,314 INFO waiting: 360849 / active: 1 / done: 166 2024-04-21 23:16:12,314 INFO ================================================================================ 2024-04-21 23:16:12,314 INFO 2024-04-21 23:16:12,314 INFO 2024-04-21 23:16:12,314 INFO ================================================================================ 2024-04-21 23:16:12,314 INFO 2024-04-21 23:13:55 15s elapsed 2024-04-21 23:16:12,314 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,314 INFO > Global (OK=376 KO=0 ) 2024-04-21 23:16:12,314 INFO > userRestLogin (OK=376 KO=0 ) 2024-04-21 23:16:12,314 INFO 2024-04-21 23:16:12,314 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,314 INFO [ ] 0% 2024-04-21 23:16:12,314 INFO waiting: 360640 / active: 0 / done: 376 2024-04-21 23:16:12,314 INFO ================================================================================ 2024-04-21 23:16:12,314 INFO 2024-04-21 23:16:12,315 INFO 2024-04-21 23:16:12,315 INFO ================================================================================ 2024-04-21 23:16:12,315 INFO 2024-04-21 23:14:00 20s elapsed 2024-04-21 23:16:12,315 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,315 INFO > Global (OK=667 KO=0 ) 2024-04-21 23:16:12,315 INFO > userRestLogin (OK=667 KO=0 ) 2024-04-21 23:16:12,315 INFO 2024-04-21 23:16:12,315 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,315 INFO [- ] 0% 2024-04-21 23:16:12,315 INFO waiting: 360348 / active: 1 / done: 667 2024-04-21 23:16:12,315 INFO ================================================================================ 2024-04-21 23:16:12,315 INFO 2024-04-21 23:16:12,315 INFO 2024-04-21 23:16:12,315 INFO ================================================================================ 2024-04-21 23:16:12,315 INFO 2024-04-21 23:14:05 25s elapsed 2024-04-21 23:16:12,315 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,315 INFO > Global (OK=1041 KO=0 ) 2024-04-21 23:16:12,315 INFO > userRestLogin (OK=1041 KO=0 ) 2024-04-21 23:16:12,315 INFO 2024-04-21 23:16:12,315 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,315 INFO [- ] 0% 2024-04-21 23:16:12,315 INFO waiting: 359974 / active: 1 / done: 1041 2024-04-21 23:16:12,315 INFO ================================================================================ 2024-04-21 23:16:12,315 INFO 2024-04-21 23:16:12,315 INFO 2024-04-21 23:16:12,315 INFO ================================================================================ 2024-04-21 23:16:12,315 INFO 2024-04-21 23:14:10 30s elapsed 2024-04-21 23:16:12,315 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,315 INFO > Global (OK=1499 KO=0 ) 2024-04-21 23:16:12,315 INFO > userRestLogin (OK=1499 KO=0 ) 2024-04-21 23:16:12,315 INFO 2024-04-21 23:16:12,315 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,315 INFO [- ] 0% 2024-04-21 23:16:12,315 INFO waiting: 359516 / active: 1 / done: 1499 2024-04-21 23:16:12,315 INFO ================================================================================ 2024-04-21 23:16:12,315 INFO 2024-04-21 23:16:12,315 INFO 2024-04-21 23:16:12,315 INFO ================================================================================ 2024-04-21 23:16:12,316 INFO 2024-04-21 23:14:15 35s elapsed 2024-04-21 23:16:12,316 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,316 INFO > Global (OK=2007 KO=0 ) 2024-04-21 23:16:12,316 INFO > userRestLogin (OK=2007 KO=0 ) 2024-04-21 23:16:12,316 INFO 2024-04-21 23:16:12,316 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,316 INFO [- ] 0% 2024-04-21 23:16:12,316 INFO waiting: 359007 / active: 2 / done: 2007 2024-04-21 23:16:12,316 INFO ================================================================================ 2024-04-21 23:16:12,316 INFO 2024-04-21 23:16:12,316 INFO 2024-04-21 23:16:12,316 INFO ================================================================================ 2024-04-21 23:16:12,316 INFO 2024-04-21 23:14:20 40s elapsed 2024-04-21 23:16:12,316 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,316 INFO > Global (OK=2515 KO=0 ) 2024-04-21 23:16:12,316 INFO [...] 2024-04-21 23:16:12,318 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,318 INFO > Global (OK=358014 KO=0 ) 2024-04-21 23:16:12,319 INFO > userRestLogin (OK=358014 KO=0 ) 2024-04-21 23:16:12,319 INFO 2024-04-21 23:16:12,319 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,319 INFO [######################################################################### ] 99% 2024-04-21 23:16:12,319 INFO waiting: 3002 / active: 0 / done: 358014 2024-04-21 23:16:12,319 INFO ================================================================================ 2024-04-21 23:16:12,319 INFO 2024-04-21 23:16:12,319 INFO 2024-04-21 23:16:12,319 INFO ================================================================================ 2024-04-21 23:16:12,319 INFO 2024-04-22 00:13:45 3605s elapsed 2024-04-21 23:16:12,319 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,319 INFO > Global (OK=358527 KO=0 ) 2024-04-21 23:16:12,319 INFO > userRestLogin (OK=358527 KO=0 ) 2024-04-21 23:16:12,319 INFO 2024-04-21 23:16:12,319 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,319 INFO [######################################################################### ] 99% 2024-04-21 23:16:12,319 INFO waiting: 2489 / active: 0 / done: 358527 2024-04-21 23:16:12,319 INFO ================================================================================ 2024-04-21 23:16:12,319 INFO 2024-04-21 23:16:12,319 INFO 2024-04-21 23:16:12,319 INFO ================================================================================ 2024-04-21 23:16:12,319 INFO 2024-04-22 00:13:50 3610s elapsed 2024-04-21 23:16:12,319 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,319 INFO > Global (OK=359036 KO=0 ) 2024-04-21 23:16:12,319 INFO > userRestLogin (OK=359036 KO=0 ) 2024-04-21 23:16:12,319 INFO 2024-04-21 23:16:12,319 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,319 INFO [######################################################################### ] 99% 2024-04-21 23:16:12,319 INFO waiting: 1980 / active: 0 / done: 359036 2024-04-21 23:16:12,319 INFO ================================================================================ 2024-04-21 23:16:12,319 INFO 2024-04-21 23:16:12,319 INFO 2024-04-21 23:16:12,319 INFO ================================================================================ 2024-04-21 23:16:12,319 INFO 2024-04-22 00:13:55 3615s elapsed 2024-04-21 23:16:12,319 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,319 INFO > Global (OK=359502 KO=0 ) 2024-04-21 23:16:12,319 INFO > userRestLogin (OK=359502 KO=0 ) 2024-04-21 23:16:12,319 INFO 2024-04-21 23:16:12,319 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,319 INFO [#########################################################################-] 99% 2024-04-21 23:16:12,320 INFO waiting: 1513 / active: 1 / done: 359502 2024-04-21 23:16:12,320 INFO ================================================================================ 2024-04-21 23:16:12,320 INFO 2024-04-21 23:16:12,320 INFO 2024-04-21 23:16:12,320 INFO ================================================================================ 2024-04-21 23:16:12,320 INFO 2024-04-22 00:14:00 3620s elapsed 2024-04-21 23:16:12,320 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,320 INFO > Global (OK=359994 KO=0 ) 2024-04-21 23:16:12,320 INFO > userRestLogin (OK=359994 KO=0 ) 2024-04-21 23:16:12,320 INFO 2024-04-21 23:16:12,320 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,320 INFO [#########################################################################-] 99% 2024-04-21 23:16:12,320 INFO waiting: 1021 / active: 1 / done: 359994 2024-04-21 23:16:12,320 INFO ================================================================================ 2024-04-21 23:16:12,320 INFO 2024-04-21 23:16:12,320 INFO 2024-04-21 23:16:12,320 INFO ================================================================================ 2024-04-21 23:16:12,320 INFO 2024-04-22 00:14:05 3625s elapsed 2024-04-21 23:16:12,320 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,320 INFO > Global (OK=360499 KO=0 ) 2024-04-21 23:16:12,320 INFO > userRestLogin (OK=360499 KO=0 ) 2024-04-21 23:16:12,320 INFO 2024-04-21 23:16:12,320 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,320 INFO [#########################################################################-] 99% 2024-04-21 23:16:12,320 INFO waiting: 516 / active: 1 / done: 360499 2024-04-21 23:16:12,320 INFO ================================================================================ 2024-04-21 23:16:12,320 INFO 2024-04-21 23:16:12,320 INFO 2024-04-21 23:16:12,320 INFO ================================================================================ 2024-04-21 23:16:12,320 INFO 2024-04-22 00:14:10 3630s elapsed 2024-04-21 23:16:12,320 INFO ---- Requests ------------------------------------------------------------------ 2024-04-21 23:16:12,320 INFO > Global (OK=361016 KO=0 ) 2024-04-21 23:16:12,320 INFO > userRestLogin (OK=361016 KO=0 ) 2024-04-21 23:16:12,320 INFO 2024-04-21 23:16:12,320 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-21 23:16:12,320 INFO [##########################################################################]100% 2024-04-21 23:16:12,320 INFO waiting: 0 / active: 0 / done: 361016 2024-04-21 23:16:12,320 INFO ================================================================================ 2024-04-21 23:16:12,320 INFO 2024-04-21 23:16:12,320 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-21 23:16:12,320 INFO 2024-04-21 23:16:12,321 INFO ---------------------- 2024-04-21 23:16:12,321 INFO [22/Apr/2024 00:14:10] Create tar file authn-users-100.tar.gz 2024-04-21 23:16:12,321 INFO ---------------------- 2024-04-21 23:16:12,321 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-21 23:16:12,321 INFO RESULT_FOLDER=authn-users-100 2024-04-21 23:16:12,321 INFO SIMULATION_PATH=authn-users-100/loadtest-20240421221339359 2024-04-21 23:16:12,321 INFO PWD=/results/gatling/authn-users-100/loadtest-20240421221339359 2024-04-21 23:16:12,321 INFO [cmd] tar -czvf /results/gatling//authn-users-100.tar.gz --exclude=simulation.log * 2024-04-21 23:16:12,321 INFO /results/gatling 2024-04-21 23:16:12,321 INFO 2024-04-21 23:16:12,321 INFO ---------------------- 2024-04-21 23:16:12,321 INFO [22/Apr/2024 00:14:10] Update /results/gatling//latest 2024-04-21 23:16:12,321 INFO ---------------------- 2024-04-21 23:16:12,321 INFO 2024-04-21 23:16:12,321 INFO ---------------------- 2024-04-21 23:16:12,321 INFO [22/Apr/2024 00:14:10] Update /results/gatling//latest.tar.gz 2024-04-21 23:16:12,321 INFO ---------------------- 2024-04-21 23:16:12,321 INFO ________________________________________________________________________________ [2024-04-21 23:16:12] authn-users-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-21 23:16:12,322 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-21 23:16:12,322 DEBUG ----- output ----- 2024-04-21 23:16:12,322 DEBUG 2024-04-21 23:16:12,322 INFO ________________________________________________________________________________ [2024-04-21 23:16:12] 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-21 23:16:12] authn-users-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-21 23:16:12] authn-users-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped