--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-12 21:09:47] authn-users-100 pre : N/A ________________________________________________________________________________ [2024-04-12 21:09:47] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-12 21:09:47] - 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-12 21:09:47,753 INFO 2024-04-12 21:09:47,753 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 21:09:47,753 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 21:09:48,067 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 21:09:48,067 INFO [loop_until]: OK (rc = 0) 2024-04-12 21:09:48,067 DEBUG --- stdout --- 2024-04-12 21:09:48,067 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 21:09:48,067 DEBUG --- stderr --- 2024-04-12 21:09:48,067 DEBUG [2024-04-12 21:09:48] - INFO: Get overseer pod resources requests memory 2024-04-12 21:09:48,068 INFO 2024-04-12 21:09:48,068 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-59d79dcbf-spbbl --output jsonpath={.spec.containers[].resources.requests.memory} 2024-04-12 21:09:48,068 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 21:09:48,364 INFO [loop_until]: OK (rc = 0) 2024-04-12 21:09:48,364 DEBUG --- stdout --- 2024-04-12 21:09:48,364 DEBUG 4Gi 2024-04-12 21:09:48,364 DEBUG --- stderr --- 2024-04-12 21:09:48,364 DEBUG ________________________________________________________________________________ [2024-04-12 21:09:48] authn-users-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-12 21:09:48,365 INFO 2024-04-12 21:09:48,365 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 21:09:48,365 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 21:09:48,668 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 21:09:48,668 INFO [loop_until]: OK (rc = 0) 2024-04-12 21:09:48,668 DEBUG --- stdout --- 2024-04-12 21:09:48,669 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 21:09:48,669 DEBUG --- stderr --- 2024-04-12 21:09:48,669 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-12 21:09:48] authn-users-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-12 21:09:48,669 INFO 2024-04-12 21:09:48,669 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 21:09:48,669 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 21:09:48,971 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 21:09:48,971 INFO [loop_until]: OK (rc = 0) 2024-04-12 21:09:48,971 DEBUG --- stdout --- 2024-04-12 21:09:48,971 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 21:09:48,971 DEBUG --- stderr --- 2024-04-12 21:09:48,971 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-12 21:09:48] 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-12 21:09:48,973 INFO 2024-04-12 21:09:48,973 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-12 21:09:48,973 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-12 21:09:49,203 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-12 21:09:49,203 INFO [loop_until]: OK (rc = 0) 2024-04-12 21:09:49,203 DEBUG --- stdout --- 2024-04-12 21:09:49,203 DEBUG WAITING 2024-04-12 21:09:49,203 DEBUG --- stderr --- 2024-04-12 21:09:49,203 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-12 21:09:49] authn-users-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-12 21:09:49,204 INFO 2024-04-12 21:09:49,204 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 21:09:49,204 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 21:09:49,509 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 21:09:49,509 INFO [loop_until]: OK (rc = 0) 2024-04-12 21:09:49,509 DEBUG --- stdout --- 2024-04-12 21:09:49,509 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 21:09:49,509 DEBUG --- stderr --- 2024-04-12 21:09:49,509 DEBUG 2024-04-12 21:09:49,509 INFO 2024-04-12 21:09:49,509 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-59d79dcbf-spbbl:/results/orders/order.json 2024-04-12 21:09:49,509 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 21:09:50,960 INFO [loop_until]: OK (rc = 0) 2024-04-12 21:09:50,960 DEBUG --- stdout --- 2024-04-12 21:09:50,960 DEBUG 2024-04-12 21:09:50,961 DEBUG --- stderr --- 2024-04-12 21:09:50,961 DEBUG Order has been successfully forwarded to overseer [2024-04-12 21:09:50] - INFO: Check if Gatling simulation started successfully 2024-04-12 21:09:50,961 INFO 2024-04-12 21:09:50,961 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 21:09:50,961 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 21:09:51,279 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 21:09:51,280 INFO [loop_until]: OK (rc = 0) 2024-04-12 21:09:51,280 DEBUG --- stdout --- 2024-04-12 21:09:51,280 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 21:09:51,280 DEBUG --- stderr --- 2024-04-12 21:09:51,280 DEBUG 2024-04-12 21:09:51,281 INFO 2024-04-12 21:09:51,281 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl -- head -n 25 /results/orders/authn-users-100/stdout.txt | grep "started..." 2024-04-12 21:09:51,281 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-12 21:09:52,096 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-12 21:09:57,883 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-12 21:10:03,706 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-12 21:10:09,501 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-12 21:10:09,501 INFO [loop_until]: OK (rc = 0) 2024-04-12 21:10:09,501 DEBUG --- stdout --- 2024-04-12 21:10:09,501 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 22:09:54.347 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 22:10:00.800 [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:10:00.803 [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:10:03.209 [WARN ] i.g.c.ZincCompiler$ - three warnings found Simulation com.forgerock.gatling.Loadtest started... 2024-04-12 21:10:09,501 DEBUG --- stderr --- 2024-04-12 21:10:09,501 DEBUG ________________________________________________________________________________ [2024-04-12 21:10:09] 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, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 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-12 22:12:19] authn-users-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-12 22:12:19,754 INFO 2024-04-12 22:12:19,754 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:19,754 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:20,107 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:20,107 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:20,107 DEBUG --- stdout --- 2024-04-12 22:12:20,107 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:20,108 DEBUG --- stderr --- 2024-04-12 22:12:20,108 DEBUG 2024-04-12 22:12:20,108 INFO 2024-04-12 22:12:20,108 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl -- rm -f /results/orders/order.json 2024-04-12 22:12:20,108 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 22:12:20,919 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:20,920 DEBUG --- stdout --- 2024-04-12 22:12:20,920 DEBUG 2024-04-12 22:12:20,920 DEBUG --- stderr --- 2024-04-12 22:12:20,920 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-12 22:12:20] authn-users-100 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-12 22:12:20] - 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-12 22:12:20,921 INFO 2024-04-12 22:12:20,921 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:20,921 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:21,223 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:21,224 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:21,224 DEBUG --- stdout --- 2024-04-12 22:12:21,224 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:21,224 DEBUG --- stderr --- 2024-04-12 22:12:21,224 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-12 22:12:21,224 INFO 2024-04-12 22:12:21,224 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:21,224 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:21,524 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:21,524 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:21,524 DEBUG --- stdout --- 2024-04-12 22:12:21,524 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:21,525 DEBUG --- stderr --- 2024-04-12 22:12:21,525 DEBUG 2024-04-12 22:12:21,525 INFO 2024-04-12 22:12:21,525 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-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-12 22:12:21,525 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 22:12:22,344 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-12 22:12:22,344 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:22,345 DEBUG --- stdout --- 2024-04-12 22:12:22,345 DEBUG /results/orders/authn-users-100/rc.txt file found 2024-04-12 22:12:22,345 DEBUG --- stderr --- 2024-04-12 22:12:22,345 DEBUG 2024-04-12 22:12:22,345 INFO 2024-04-12 22:12:22,345 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:22,345 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:22,650 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:22,651 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:22,651 DEBUG --- stdout --- 2024-04-12 22:12:22,651 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:22,651 DEBUG --- stderr --- 2024-04-12 22:12:22,651 DEBUG 2024-04-12 22:12:22,651 INFO 2024-04-12 22:12:22,651 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-100/rc.txt 2024-04-12 22:12:23,488 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-12 22:12:23,488 DEBUG --- stdout --- 2024-04-12 22:12:23,488 DEBUG 4 /results/orders/authn-users-100/rc.txt 2024-04-12 22:12:23,488 DEBUG --- stderr --- 2024-04-12 22:12:23,488 DEBUG 2024-04-12 22:12:23,489 INFO - Download file (size 4k) 2024-04-12 22:12:23,489 INFO 2024-04-12 22:12:23,489 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:23,489 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:23,816 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:23,817 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:23,817 DEBUG --- stdout --- 2024-04-12 22:12:23,817 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:23,817 DEBUG --- stderr --- 2024-04-12 22:12:23,817 DEBUG source : /results/orders/authn-users-100/rc.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-12 22:12:23,817 INFO 2024-04-12 22:12:23,817 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:23,817 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:24,150 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:24,151 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:24,152 DEBUG --- stdout --- 2024-04-12 22:12:24,152 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:24,152 DEBUG --- stderr --- 2024-04-12 22:12:24,152 DEBUG 2024-04-12 22:12:24,152 INFO 2024-04-12 22:12:24,153 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-100/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-12 22:12:24,153 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-12 22:12:24,913 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:24,914 DEBUG --- stdout --- 2024-04-12 22:12:24,914 DEBUG tar: Removing leading `/' from member names 2024-04-12 22:12:24,914 DEBUG --- stderr --- 2024-04-12 22:12:24,914 DEBUG 2024-04-12 22:12:24,915 INFO 2024-04-12 22:12:24,915 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:24,915 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:25,241 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:25,241 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:25,241 DEBUG --- stdout --- 2024-04-12 22:12:25,241 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:25,241 DEBUG --- stderr --- 2024-04-12 22:12:25,241 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-12 22:12:25,242 INFO 2024-04-12 22:12:25,242 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:25,242 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:25,556 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:25,557 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:25,557 DEBUG --- stdout --- 2024-04-12 22:12:25,557 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:25,557 DEBUG --- stderr --- 2024-04-12 22:12:25,557 DEBUG 2024-04-12 22:12:25,557 INFO 2024-04-12 22:12:25,557 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-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-12 22:12:25,557 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 22:12:26,409 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-12 22:12:26,409 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:26,409 DEBUG --- stdout --- 2024-04-12 22:12:26,409 DEBUG /results/orders/authn-users-100/stderr.txt file found 2024-04-12 22:12:26,409 DEBUG --- stderr --- 2024-04-12 22:12:26,409 DEBUG 2024-04-12 22:12:26,410 INFO 2024-04-12 22:12:26,410 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:26,410 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:26,737 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:26,737 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:26,737 DEBUG --- stdout --- 2024-04-12 22:12:26,737 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:26,737 DEBUG --- stderr --- 2024-04-12 22:12:26,737 DEBUG 2024-04-12 22:12:26,737 INFO 2024-04-12 22:12:26,738 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-100/stderr.txt 2024-04-12 22:12:27,589 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-12 22:12:27,590 DEBUG --- stdout --- 2024-04-12 22:12:27,590 DEBUG 0 /results/orders/authn-users-100/stderr.txt 2024-04-12 22:12:27,590 DEBUG --- stderr --- 2024-04-12 22:12:27,590 DEBUG 2024-04-12 22:12:27,590 INFO - Download file (size 0k) 2024-04-12 22:12:27,590 INFO 2024-04-12 22:12:27,591 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:27,591 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:27,917 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:27,917 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:27,917 DEBUG --- stdout --- 2024-04-12 22:12:27,917 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:27,917 DEBUG --- stderr --- 2024-04-12 22:12:27,917 DEBUG source : /results/orders/authn-users-100/stderr.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-12 22:12:27,918 INFO 2024-04-12 22:12:27,918 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:27,918 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:28,261 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:28,262 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:28,262 DEBUG --- stdout --- 2024-04-12 22:12:28,262 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:28,262 DEBUG --- stderr --- 2024-04-12 22:12:28,262 DEBUG 2024-04-12 22:12:28,262 INFO 2024-04-12 22:12:28,262 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-100/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-12 22:12:28,262 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-12 22:12:29,081 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:29,081 DEBUG --- stdout --- 2024-04-12 22:12:29,081 DEBUG tar: Removing leading `/' from member names 2024-04-12 22:12:29,081 DEBUG --- stderr --- 2024-04-12 22:12:29,081 DEBUG 2024-04-12 22:12:29,082 INFO 2024-04-12 22:12:29,082 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:29,082 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:29,446 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:29,447 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:29,447 DEBUG --- stdout --- 2024-04-12 22:12:29,447 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:29,447 DEBUG --- stderr --- 2024-04-12 22:12:29,448 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-12 22:12:29,448 INFO 2024-04-12 22:12:29,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-12 22:12:29,448 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:29,765 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:29,765 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:29,765 DEBUG --- stdout --- 2024-04-12 22:12:29,765 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:29,765 DEBUG --- stderr --- 2024-04-12 22:12:29,765 DEBUG 2024-04-12 22:12:29,766 INFO 2024-04-12 22:12:29,766 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-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-12 22:12:29,766 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 22:12:30,600 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-12 22:12:30,600 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:30,600 DEBUG --- stdout --- 2024-04-12 22:12:30,600 DEBUG /results/orders/authn-users-100/stdout.txt file found 2024-04-12 22:12:30,600 DEBUG --- stderr --- 2024-04-12 22:12:30,600 DEBUG 2024-04-12 22:12:30,601 INFO 2024-04-12 22:12:30,601 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:30,601 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:30,907 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:30,908 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:30,908 DEBUG --- stdout --- 2024-04-12 22:12:30,908 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:30,908 DEBUG --- stderr --- 2024-04-12 22:12:30,908 DEBUG 2024-04-12 22:12:30,908 INFO 2024-04-12 22:12:30,908 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-100/stdout.txt 2024-04-12 22:12:31,752 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-12 22:12:31,752 DEBUG --- stdout --- 2024-04-12 22:12:31,752 DEBUG 504 /results/orders/authn-users-100/stdout.txt 2024-04-12 22:12:31,752 DEBUG --- stderr --- 2024-04-12 22:12:31,752 DEBUG 2024-04-12 22:12:31,752 INFO - Download file (size 504k) 2024-04-12 22:12:31,753 INFO 2024-04-12 22:12:31,753 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:31,753 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:32,057 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:32,058 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:32,058 DEBUG --- stdout --- 2024-04-12 22:12:32,058 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:32,058 DEBUG --- stderr --- 2024-04-12 22:12:32,058 DEBUG source : /results/orders/authn-users-100/stdout.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-12 22:12:32,058 INFO 2024-04-12 22:12:32,058 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:32,058 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:32,358 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:32,358 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:32,359 DEBUG --- stdout --- 2024-04-12 22:12:32,359 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:32,359 DEBUG --- stderr --- 2024-04-12 22:12:32,359 DEBUG 2024-04-12 22:12:32,359 INFO 2024-04-12 22:12:32,359 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-100/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-12 22:12:32,359 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-12 22:12:33,441 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:33,441 DEBUG --- stdout --- 2024-04-12 22:12:33,441 DEBUG tar: Removing leading `/' from member names 2024-04-12 22:12:33,441 DEBUG --- stderr --- 2024-04-12 22:12:33,441 DEBUG ________________________________________________________________________________ [2024-04-12 22:12:33] authn-users-100 show_cmd : Display order command ________________________________________________________________________________ 2024-04-12 22:12:33,442 INFO 2024-04-12 22:12:33,442 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:33,442 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:33,761 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:33,761 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:33,761 DEBUG --- stdout --- 2024-04-12 22:12:33,761 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:33,761 DEBUG --- stderr --- 2024-04-12 22:12:33,761 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-12 22:12:33,761 INFO 2024-04-12 22:12:33,761 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:33,761 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:34,125 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:34,126 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:34,126 DEBUG --- stdout --- 2024-04-12 22:12:34,126 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:34,126 DEBUG --- stderr --- 2024-04-12 22:12:34,126 DEBUG 2024-04-12 22:12:34,127 INFO 2024-04-12 22:12:34,127 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-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-12 22:12:34,127 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 22:12:34,965 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-12 22:12:34,965 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:34,966 DEBUG --- stdout --- 2024-04-12 22:12:34,966 DEBUG /results/orders/authn-users-100/cmd.txt file found 2024-04-12 22:12:34,966 DEBUG --- stderr --- 2024-04-12 22:12:34,966 DEBUG 2024-04-12 22:12:34,967 INFO 2024-04-12 22:12:34,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-12 22:12:34,967 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:35,349 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:35,349 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:35,349 DEBUG --- stdout --- 2024-04-12 22:12:35,349 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:35,350 DEBUG --- stderr --- 2024-04-12 22:12:35,350 DEBUG 2024-04-12 22:12:35,350 INFO 2024-04-12 22:12:35,350 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-100/cmd.txt 2024-04-12 22:12:36,269 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-12 22:12:36,269 DEBUG --- stdout --- 2024-04-12 22:12:36,270 DEBUG 4 /results/orders/authn-users-100/cmd.txt 2024-04-12 22:12:36,270 DEBUG --- stderr --- 2024-04-12 22:12:36,270 DEBUG 2024-04-12 22:12:36,270 INFO - Download file (size 4k) 2024-04-12 22:12:36,270 INFO 2024-04-12 22:12:36,270 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:36,270 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:36,592 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:36,593 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:36,593 DEBUG --- stdout --- 2024-04-12 22:12:36,593 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:36,593 DEBUG --- stderr --- 2024-04-12 22:12:36,593 DEBUG source : /results/orders/authn-users-100/cmd.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-12 22:12:36,593 INFO 2024-04-12 22:12:36,594 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 22:12:36,594 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 22:12:36,927 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 22:12:36,927 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:36,927 DEBUG --- stdout --- 2024-04-12 22:12:36,927 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 22:12:36,927 DEBUG --- stderr --- 2024-04-12 22:12:36,927 DEBUG 2024-04-12 22:12:36,928 INFO 2024-04-12 22:12:36,928 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-100/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-12 22:12:36,928 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-12 22:12:37,692 INFO [loop_until]: OK (rc = 0) 2024-04-12 22:12:37,692 DEBUG --- stdout --- 2024-04-12 22:12:37,692 DEBUG tar: Removing leading `/' from member names 2024-04-12 22:12:37,692 DEBUG --- stderr --- 2024-04-12 22:12:37,692 DEBUG 2024-04-12 22:12:37,692 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-12 22:12:37,692 DEBUG ----- output ----- 2024-04-12 22:12:37,693 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-12 22:12:37,693 INFO ________________________________________________________________________________ [2024-04-12 22:12:37] authn-users-100 show_rc : Display rc order ________________________________________________________________________________ 2024-04-12 22:12:37,693 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-12 22:12:37,693 DEBUG ----- output ----- 2024-04-12 22:12:37,693 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-12 22:12:37,693 INFO ________________________________________________________________________________ [2024-04-12 22:12:37] authn-users-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-12 22:12:37,695 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 8753 lines): 2024-04-12 22:12:37,695 DEBUG ----- output ----- 2024-04-12 22:12:37,695 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-12 22:12:37,695 INFO 22:09:54.347 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-12 22:12:37,695 INFO 22:10:00.800 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-12 22:12:37,695 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-12 22:12:37,695 INFO ^ 2024-04-12 22:12:37,695 INFO 22:10:00.803 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-12 22:12:37,695 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-12 22:12:37,695 INFO ^ 2024-04-12 22:12:37,695 INFO 22:10:03.209 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-12 22:12:37,695 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-12 22:12:37,695 INFO 2024-04-12 22:12:37,695 INFO ================================================================================ 2024-04-12 22:12:37,695 INFO 2024-04-12 22:10:12 5s elapsed 2024-04-12 22:12:37,695 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,695 INFO > Global (OK=41 KO=0 ) 2024-04-12 22:12:37,695 INFO > userRestLogin (OK=41 KO=0 ) 2024-04-12 22:12:37,695 INFO 2024-04-12 22:12:37,695 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,695 INFO [- ] 0% 2024-04-12 22:12:37,695 INFO waiting: 362151 / active: 1 / done: 41 2024-04-12 22:12:37,695 INFO ================================================================================ 2024-04-12 22:12:37,695 INFO 2024-04-12 22:12:37,695 INFO 2024-04-12 22:12:37,695 INFO ================================================================================ 2024-04-12 22:12:37,695 INFO 2024-04-12 22:10:17 10s elapsed 2024-04-12 22:12:37,695 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,695 INFO > Global (OK=166 KO=0 ) 2024-04-12 22:12:37,695 INFO > userRestLogin (OK=166 KO=0 ) 2024-04-12 22:12:37,695 INFO 2024-04-12 22:12:37,695 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,696 INFO [- ] 0% 2024-04-12 22:12:37,696 INFO waiting: 362026 / active: 1 / done: 166 2024-04-12 22:12:37,696 INFO ================================================================================ 2024-04-12 22:12:37,696 INFO 2024-04-12 22:12:37,696 INFO 2024-04-12 22:12:37,696 INFO ================================================================================ 2024-04-12 22:12:37,696 INFO 2024-04-12 22:10:22 15s elapsed 2024-04-12 22:12:37,696 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,696 INFO > Global (OK=375 KO=0 ) 2024-04-12 22:12:37,696 INFO > userRestLogin (OK=375 KO=0 ) 2024-04-12 22:12:37,696 INFO 2024-04-12 22:12:37,696 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,696 INFO [- ] 0% 2024-04-12 22:12:37,696 INFO waiting: 361817 / active: 1 / done: 375 2024-04-12 22:12:37,696 INFO ================================================================================ 2024-04-12 22:12:37,696 INFO 2024-04-12 22:12:37,696 INFO 2024-04-12 22:12:37,696 INFO ================================================================================ 2024-04-12 22:12:37,696 INFO 2024-04-12 22:10:27 20s elapsed 2024-04-12 22:12:37,696 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,696 INFO > Global (OK=666 KO=0 ) 2024-04-12 22:12:37,696 INFO > userRestLogin (OK=666 KO=0 ) 2024-04-12 22:12:37,696 INFO 2024-04-12 22:12:37,696 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,696 INFO [- ] 0% 2024-04-12 22:12:37,696 INFO waiting: 361526 / active: 1 / done: 666 2024-04-12 22:12:37,696 INFO ================================================================================ 2024-04-12 22:12:37,696 INFO 2024-04-12 22:12:37,696 INFO 2024-04-12 22:12:37,696 INFO ================================================================================ 2024-04-12 22:12:37,696 INFO 2024-04-12 22:10:32 25s elapsed 2024-04-12 22:12:37,696 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,696 INFO > Global (OK=1041 KO=0 ) 2024-04-12 22:12:37,696 INFO > userRestLogin (OK=1041 KO=0 ) 2024-04-12 22:12:37,696 INFO 2024-04-12 22:12:37,696 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,696 INFO [- ] 0% 2024-04-12 22:12:37,696 INFO waiting: 361151 / active: 1 / done: 1041 2024-04-12 22:12:37,696 INFO ================================================================================ 2024-04-12 22:12:37,696 INFO 2024-04-12 22:12:37,697 INFO 2024-04-12 22:12:37,697 INFO ================================================================================ 2024-04-12 22:12:37,697 INFO 2024-04-12 22:10:37 30s elapsed 2024-04-12 22:12:37,697 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,697 INFO > Global (OK=1498 KO=0 ) 2024-04-12 22:12:37,697 INFO > userRestLogin (OK=1498 KO=0 ) 2024-04-12 22:12:37,697 INFO 2024-04-12 22:12:37,697 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,697 INFO [- ] 0% 2024-04-12 22:12:37,697 INFO waiting: 360692 / active: 3 / done: 1498 2024-04-12 22:12:37,697 INFO ================================================================================ 2024-04-12 22:12:37,697 INFO 2024-04-12 22:12:37,697 INFO 2024-04-12 22:12:37,697 INFO ================================================================================ 2024-04-12 22:12:37,697 INFO 2024-04-12 22:10:42 35s elapsed 2024-04-12 22:12:37,697 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,697 INFO > Global (OK=2032 KO=0 ) 2024-04-12 22:12:37,697 INFO > userRestLogin (OK=2032 KO=0 ) 2024-04-12 22:12:37,697 INFO 2024-04-12 22:12:37,697 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,697 INFO [- ] 0% 2024-04-12 22:12:37,697 INFO waiting: 360160 / active: 1 / done: 2032 2024-04-12 22:12:37,697 INFO ================================================================================ 2024-04-12 22:12:37,697 INFO 2024-04-12 22:12:37,697 INFO 2024-04-12 22:12:37,697 INFO ================================================================================ 2024-04-12 22:12:37,697 INFO 2024-04-12 22:10:47 40s elapsed 2024-04-12 22:12:37,697 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,697 INFO > Global (OK=2492 KO=0 ) 2024-04-12 22:12:37,697 INFO > userRestLogin (OK=2492 KO=0 ) 2024-04-12 22:12:37,697 INFO [...] 2024-04-12 22:12:37,700 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,700 INFO > Global (OK=359683 KO=0 ) 2024-04-12 22:12:37,700 INFO > userRestLogin (OK=359683 KO=0 ) 2024-04-12 22:12:37,700 INFO 2024-04-12 22:12:37,700 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,700 INFO [#########################################################################-] 99% 2024-04-12 22:12:37,700 INFO waiting: 2509 / active: 1 / done: 359683 2024-04-12 22:12:37,700 INFO ================================================================================ 2024-04-12 22:12:37,700 INFO 2024-04-12 22:12:37,700 INFO 2024-04-12 22:12:37,700 INFO ================================================================================ 2024-04-12 22:12:37,700 INFO 2024-04-12 23:10:17 3610s elapsed 2024-04-12 22:12:37,700 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,700 INFO > Global (OK=360174 KO=0 ) 2024-04-12 22:12:37,700 INFO > userRestLogin (OK=360174 KO=0 ) 2024-04-12 22:12:37,700 INFO 2024-04-12 22:12:37,700 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,700 INFO [#########################################################################-] 99% 2024-04-12 22:12:37,700 INFO waiting: 2016 / active: 3 / done: 360174 2024-04-12 22:12:37,700 INFO ================================================================================ 2024-04-12 22:12:37,700 INFO 2024-04-12 22:12:37,700 INFO 2024-04-12 22:12:37,700 INFO ================================================================================ 2024-04-12 22:12:37,700 INFO 2024-04-12 23:10:22 3615s elapsed 2024-04-12 22:12:37,700 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,700 INFO > Global (OK=360689 KO=0 ) 2024-04-12 22:12:37,700 INFO > userRestLogin (OK=360689 KO=0 ) 2024-04-12 22:12:37,700 INFO 2024-04-12 22:12:37,700 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,700 INFO [#########################################################################-] 99% 2024-04-12 22:12:37,700 INFO waiting: 1503 / active: 1 / done: 360689 2024-04-12 22:12:37,700 INFO ================================================================================ 2024-04-12 22:12:37,700 INFO 2024-04-12 22:12:37,700 INFO 2024-04-12 22:12:37,700 INFO ================================================================================ 2024-04-12 22:12:37,700 INFO 2024-04-12 23:10:27 3620s elapsed 2024-04-12 22:12:37,701 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,701 INFO > Global (OK=361204 KO=0 ) 2024-04-12 22:12:37,701 INFO > userRestLogin (OK=361204 KO=0 ) 2024-04-12 22:12:37,701 INFO 2024-04-12 22:12:37,701 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,701 INFO [######################################################################### ] 99% 2024-04-12 22:12:37,701 INFO waiting: 989 / active: 0 / done: 361204 2024-04-12 22:12:37,701 INFO ================================================================================ 2024-04-12 22:12:37,701 INFO 2024-04-12 22:12:37,701 INFO 2024-04-12 22:12:37,701 INFO ================================================================================ 2024-04-12 22:12:37,701 INFO 2024-04-12 23:10:32 3625s elapsed 2024-04-12 22:12:37,701 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,701 INFO > Global (OK=361703 KO=0 ) 2024-04-12 22:12:37,701 INFO > userRestLogin (OK=361703 KO=0 ) 2024-04-12 22:12:37,701 INFO 2024-04-12 22:12:37,701 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,701 INFO [######################################################################### ] 99% 2024-04-12 22:12:37,701 INFO waiting: 490 / active: 0 / done: 361703 2024-04-12 22:12:37,701 INFO ================================================================================ 2024-04-12 22:12:37,701 INFO 2024-04-12 22:12:37,701 INFO 2024-04-12 22:12:37,701 INFO ================================================================================ 2024-04-12 22:12:37,701 INFO 2024-04-12 23:10:37 3630s elapsed 2024-04-12 22:12:37,701 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,701 INFO > Global (OK=362192 KO=0 ) 2024-04-12 22:12:37,701 INFO > userRestLogin (OK=362192 KO=0 ) 2024-04-12 22:12:37,701 INFO 2024-04-12 22:12:37,701 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,701 INFO [#########################################################################-] 99% 2024-04-12 22:12:37,701 INFO waiting: 0 / active: 1 / done: 362192 2024-04-12 22:12:37,701 INFO ================================================================================ 2024-04-12 22:12:37,701 INFO 2024-04-12 22:12:37,701 INFO 2024-04-12 22:12:37,701 INFO ================================================================================ 2024-04-12 22:12:37,701 INFO 2024-04-12 23:10:37 3630s elapsed 2024-04-12 22:12:37,701 INFO ---- Requests ------------------------------------------------------------------ 2024-04-12 22:12:37,701 INFO > Global (OK=362193 KO=0 ) 2024-04-12 22:12:37,701 INFO > userRestLogin (OK=362193 KO=0 ) 2024-04-12 22:12:37,701 INFO 2024-04-12 22:12:37,701 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-12 22:12:37,702 INFO [##########################################################################]100% 2024-04-12 22:12:37,702 INFO waiting: 0 / active: 0 / done: 362193 2024-04-12 22:12:37,702 INFO ================================================================================ 2024-04-12 22:12:37,702 INFO 2024-04-12 22:12:37,702 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-12 22:12:37,702 INFO 2024-04-12 22:12:37,702 INFO ---------------------- 2024-04-12 22:12:37,702 INFO [12/Apr/2024 23:10:37] Create tar file authn-users-100.tar.gz 2024-04-12 22:12:37,702 INFO ---------------------- 2024-04-12 22:12:37,702 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-12 22:12:37,702 INFO RESULT_FOLDER=authn-users-100 2024-04-12 22:12:37,702 INFO SIMULATION_PATH=authn-users-100/loadtest-20240412211006439 2024-04-12 22:12:37,702 INFO PWD=/results/gatling/authn-users-100/loadtest-20240412211006439 2024-04-12 22:12:37,702 INFO [cmd] tar -czvf /results/gatling//authn-users-100.tar.gz --exclude=simulation.log * 2024-04-12 22:12:37,702 INFO /results/gatling 2024-04-12 22:12:37,702 INFO 2024-04-12 22:12:37,702 INFO ---------------------- 2024-04-12 22:12:37,702 INFO [12/Apr/2024 23:10:37] Update /results/gatling//latest 2024-04-12 22:12:37,702 INFO ---------------------- 2024-04-12 22:12:37,702 INFO 2024-04-12 22:12:37,702 INFO ---------------------- 2024-04-12 22:12:37,702 INFO [12/Apr/2024 23:10:37] Update /results/gatling//latest.tar.gz 2024-04-12 22:12:37,702 INFO ---------------------- 2024-04-12 22:12:37,702 INFO ________________________________________________________________________________ [2024-04-12 22:12:37] authn-users-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-12 22:12:37,703 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-12 22:12:37,703 DEBUG ----- output ----- 2024-04-12 22:12:37,703 DEBUG 2024-04-12 22:12:37,703 INFO ________________________________________________________________________________ [2024-04-12 22:12:37] 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-12 22:12:37] authn-users-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-12 22:12:37] authn-users-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped