--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-11 01:50:02] authn-users-100 pre : N/A ________________________________________________________________________________ [2024-04-11 01:50:02] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-11 01:50:02] - 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-11 01:50:02,485 INFO 2024-04-11 01:50:02,485 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-11 01:50:02,485 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 01:50:02,813 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 01:50:02,813 INFO [loop_until]: OK (rc = 0) 2024-04-11 01:50:02,813 DEBUG --- stdout --- 2024-04-11 01:50:02,813 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 01:50:02,813 DEBUG --- stderr --- 2024-04-11 01:50:02,813 DEBUG [2024-04-11 01:50:02] - INFO: Get overseer pod resources requests memory 2024-04-11 01:50:02,814 INFO 2024-04-11 01:50:02,814 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-78845f4f8c-v2r2q --output jsonpath={.spec.containers[].resources.requests.memory} 2024-04-11 01:50:02,814 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 01:50:03,141 INFO [loop_until]: OK (rc = 0) 2024-04-11 01:50:03,141 DEBUG --- stdout --- 2024-04-11 01:50:03,142 DEBUG 4Gi 2024-04-11 01:50:03,142 DEBUG --- stderr --- 2024-04-11 01:50:03,142 DEBUG ________________________________________________________________________________ [2024-04-11 01:50:03] authn-users-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-11 01:50:03,142 INFO 2024-04-11 01:50:03,142 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-11 01:50:03,143 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 01:50:03,468 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 01:50:03,468 INFO [loop_until]: OK (rc = 0) 2024-04-11 01:50:03,468 DEBUG --- stdout --- 2024-04-11 01:50:03,468 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 01:50:03,468 DEBUG --- stderr --- 2024-04-11 01:50:03,468 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-11 01:50:03] authn-users-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-11 01:50:03,469 INFO 2024-04-11 01:50:03,469 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-11 01:50:03,469 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 01:50:03,804 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 01:50:03,804 INFO [loop_until]: OK (rc = 0) 2024-04-11 01:50:03,804 DEBUG --- stdout --- 2024-04-11 01:50:03,805 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 01:50:03,805 DEBUG --- stderr --- 2024-04-11 01:50:03,805 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-11 01:50:03] 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-11 01:50:03,807 INFO 2024-04-11 01:50:03,807 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-11 01:50:03,807 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-11 01:50:04,051 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 01:50:04,052 INFO [loop_until]: OK (rc = 0) 2024-04-11 01:50:04,052 DEBUG --- stdout --- 2024-04-11 01:50:04,052 DEBUG WAITING 2024-04-11 01:50:04,052 DEBUG --- stderr --- 2024-04-11 01:50:04,052 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 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-11 01:50:04] authn-users-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-11 01:50:04,052 INFO 2024-04-11 01:50:04,052 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-11 01:50:04,052 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 01:50:04,372 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 01:50:04,372 INFO [loop_until]: OK (rc = 0) 2024-04-11 01:50:04,372 DEBUG --- stdout --- 2024-04-11 01:50:04,372 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 01:50:04,372 DEBUG --- stderr --- 2024-04-11 01:50:04,372 DEBUG 2024-04-11 01:50:04,372 INFO 2024-04-11 01:50:04,372 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-78845f4f8c-v2r2q:/results/orders/order.json 2024-04-11 01:50:04,373 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 01:50:05,757 INFO [loop_until]: OK (rc = 0) 2024-04-11 01:50:05,758 DEBUG --- stdout --- 2024-04-11 01:50:05,758 DEBUG 2024-04-11 01:50:05,758 DEBUG --- stderr --- 2024-04-11 01:50:05,758 DEBUG Order has been successfully forwarded to overseer [2024-04-11 01:50:05] - INFO: Check if Gatling simulation started successfully 2024-04-11 01:50:05,758 INFO 2024-04-11 01:50:05,758 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-11 01:50:05,758 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 01:50:06,073 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 01:50:06,073 INFO [loop_until]: OK (rc = 0) 2024-04-11 01:50:06,074 DEBUG --- stdout --- 2024-04-11 01:50:06,074 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 01:50:06,074 DEBUG --- stderr --- 2024-04-11 01:50:06,074 DEBUG 2024-04-11 01:50:06,074 INFO 2024-04-11 01:50:06,074 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q -- head -n 25 /results/orders/authn-users-100/stdout.txt | grep "started..." 2024-04-11 01:50:06,074 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-11 01:50:06,827 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-11 01:50:12,620 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-11 01:50:18,401 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-11 01:50:24,176 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-11 01:50:24,176 INFO [loop_until]: OK (rc = 0) 2024-04-11 01:50:24,176 DEBUG --- stdout --- 2024-04-11 01:50:24,176 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 02:50:08.532 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 02:50:14.563 [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"); ^ 02:50:14.566 [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"); ^ 02:50:16.967 [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-11 01:50:24,176 DEBUG --- stderr --- 2024-04-11 01:50:24,176 DEBUG ________________________________________________________________________________ [2024-04-11 01:50:24] 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 01s, 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 03s, 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 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, 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-11 02:52:34] authn-users-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-11 02:52:34,286 INFO 2024-04-11 02:52:34,286 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-11 02:52:34,286 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:34,628 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:34,630 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:34,630 DEBUG --- stdout --- 2024-04-11 02:52:34,630 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:34,630 DEBUG --- stderr --- 2024-04-11 02:52:34,630 DEBUG 2024-04-11 02:52:34,631 INFO 2024-04-11 02:52:34,631 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q -- rm -f /results/orders/order.json 2024-04-11 02:52:34,631 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 02:52:35,494 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:35,494 DEBUG --- stdout --- 2024-04-11 02:52:35,494 DEBUG 2024-04-11 02:52:35,494 DEBUG --- stderr --- 2024-04-11 02:52:35,494 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-11 02:52:35] authn-users-100 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-11 02:52:35] - 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-11 02:52:35,495 INFO 2024-04-11 02:52:35,495 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-11 02:52:35,495 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:35,797 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:35,798 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:35,798 DEBUG --- stdout --- 2024-04-11 02:52:35,798 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:35,798 DEBUG --- stderr --- 2024-04-11 02:52:35,798 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 02:52:35,798 INFO 2024-04-11 02:52:35,798 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-11 02:52:35,798 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:36,108 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:36,108 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:36,108 DEBUG --- stdout --- 2024-04-11 02:52:36,108 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:36,108 DEBUG --- stderr --- 2024-04-11 02:52:36,108 DEBUG 2024-04-11 02:52:36,109 INFO 2024-04-11 02:52:36,109 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --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-11 02:52:36,109 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 02:52:36,927 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 02:52:36,927 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:36,927 DEBUG --- stdout --- 2024-04-11 02:52:36,927 DEBUG /results/orders/authn-users-100/rc.txt file found 2024-04-11 02:52:36,927 DEBUG --- stderr --- 2024-04-11 02:52:36,927 DEBUG 2024-04-11 02:52:36,928 INFO 2024-04-11 02:52:36,928 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-11 02:52:36,928 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:37,238 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:37,239 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:37,239 DEBUG --- stdout --- 2024-04-11 02:52:37,239 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:37,239 DEBUG --- stderr --- 2024-04-11 02:52:37,239 DEBUG 2024-04-11 02:52:37,239 INFO 2024-04-11 02:52:37,239 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-100/rc.txt 2024-04-11 02:52:38,053 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 02:52:38,054 DEBUG --- stdout --- 2024-04-11 02:52:38,054 DEBUG 4 /results/orders/authn-users-100/rc.txt 2024-04-11 02:52:38,054 DEBUG --- stderr --- 2024-04-11 02:52:38,054 DEBUG 2024-04-11 02:52:38,054 INFO - Download file (size 4k) 2024-04-11 02:52:38,054 INFO 2024-04-11 02:52:38,054 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-11 02:52:38,054 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:38,363 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:38,363 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:38,363 DEBUG --- stdout --- 2024-04-11 02:52:38,363 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:38,363 DEBUG --- stderr --- 2024-04-11 02:52:38,363 DEBUG source : /results/orders/authn-users-100/rc.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-11 02:52:38,364 INFO 2024-04-11 02:52:38,364 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-11 02:52:38,364 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:38,726 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:38,726 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:38,726 DEBUG --- stdout --- 2024-04-11 02:52:38,726 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:38,726 DEBUG --- stderr --- 2024-04-11 02:52:38,726 DEBUG 2024-04-11 02:52:38,726 INFO 2024-04-11 02:52:38,727 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/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-11 02:52:38,727 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 02:52:39,501 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:39,501 DEBUG --- stdout --- 2024-04-11 02:52:39,501 DEBUG tar: Removing leading `/' from member names 2024-04-11 02:52:39,501 DEBUG --- stderr --- 2024-04-11 02:52:39,501 DEBUG 2024-04-11 02:52:39,502 INFO 2024-04-11 02:52:39,502 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-11 02:52:39,502 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:39,864 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:39,865 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:39,866 DEBUG --- stdout --- 2024-04-11 02:52:39,866 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:39,866 DEBUG --- stderr --- 2024-04-11 02:52:39,866 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 02:52:39,866 INFO 2024-04-11 02:52:39,866 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-11 02:52:39,866 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:40,168 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:40,168 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:40,168 DEBUG --- stdout --- 2024-04-11 02:52:40,168 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:40,168 DEBUG --- stderr --- 2024-04-11 02:52:40,168 DEBUG 2024-04-11 02:52:40,169 INFO 2024-04-11 02:52:40,169 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --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-11 02:52:40,169 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 02:52:40,998 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 02:52:40,998 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:40,998 DEBUG --- stdout --- 2024-04-11 02:52:40,998 DEBUG /results/orders/authn-users-100/stderr.txt file found 2024-04-11 02:52:40,998 DEBUG --- stderr --- 2024-04-11 02:52:40,998 DEBUG 2024-04-11 02:52:40,998 INFO 2024-04-11 02:52:40,998 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-11 02:52:40,998 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:41,303 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:41,303 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:41,303 DEBUG --- stdout --- 2024-04-11 02:52:41,303 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:41,303 DEBUG --- stderr --- 2024-04-11 02:52:41,303 DEBUG 2024-04-11 02:52:41,303 INFO 2024-04-11 02:52:41,304 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-100/stderr.txt 2024-04-11 02:52:42,124 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 02:52:42,124 DEBUG --- stdout --- 2024-04-11 02:52:42,124 DEBUG 0 /results/orders/authn-users-100/stderr.txt 2024-04-11 02:52:42,124 DEBUG --- stderr --- 2024-04-11 02:52:42,124 DEBUG 2024-04-11 02:52:42,124 INFO - Download file (size 0k) 2024-04-11 02:52:42,125 INFO 2024-04-11 02:52:42,125 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-11 02:52:42,125 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:42,445 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:42,446 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:42,446 DEBUG --- stdout --- 2024-04-11 02:52:42,446 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:42,446 DEBUG --- stderr --- 2024-04-11 02:52:42,446 DEBUG source : /results/orders/authn-users-100/stderr.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-11 02:52:42,446 INFO 2024-04-11 02:52:42,446 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-11 02:52:42,446 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:42,763 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:42,763 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:42,763 DEBUG --- stdout --- 2024-04-11 02:52:42,763 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:42,763 DEBUG --- stderr --- 2024-04-11 02:52:42,763 DEBUG 2024-04-11 02:52:42,764 INFO 2024-04-11 02:52:42,764 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/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-11 02:52:42,764 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 02:52:43,529 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:43,529 DEBUG --- stdout --- 2024-04-11 02:52:43,529 DEBUG tar: Removing leading `/' from member names 2024-04-11 02:52:43,529 DEBUG --- stderr --- 2024-04-11 02:52:43,529 DEBUG 2024-04-11 02:52:43,530 INFO 2024-04-11 02:52:43,530 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-11 02:52:43,530 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:43,851 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:43,851 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:43,851 DEBUG --- stdout --- 2024-04-11 02:52:43,851 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:43,851 DEBUG --- stderr --- 2024-04-11 02:52:43,851 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 02:52:43,851 INFO 2024-04-11 02:52:43,852 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-11 02:52:43,852 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:44,179 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:44,179 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:44,179 DEBUG --- stdout --- 2024-04-11 02:52:44,179 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:44,179 DEBUG --- stderr --- 2024-04-11 02:52:44,179 DEBUG 2024-04-11 02:52:44,180 INFO 2024-04-11 02:52:44,180 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --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-11 02:52:44,180 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 02:52:45,060 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 02:52:45,062 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:45,062 DEBUG --- stdout --- 2024-04-11 02:52:45,062 DEBUG /results/orders/authn-users-100/stdout.txt file found 2024-04-11 02:52:45,062 DEBUG --- stderr --- 2024-04-11 02:52:45,062 DEBUG 2024-04-11 02:52:45,063 INFO 2024-04-11 02:52:45,063 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-11 02:52:45,063 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:45,408 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:45,408 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:45,409 DEBUG --- stdout --- 2024-04-11 02:52:45,409 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:45,409 DEBUG --- stderr --- 2024-04-11 02:52:45,409 DEBUG 2024-04-11 02:52:45,409 INFO 2024-04-11 02:52:45,409 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-100/stdout.txt 2024-04-11 02:52:46,299 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 02:52:46,299 DEBUG --- stdout --- 2024-04-11 02:52:46,299 DEBUG 504 /results/orders/authn-users-100/stdout.txt 2024-04-11 02:52:46,299 DEBUG --- stderr --- 2024-04-11 02:52:46,299 DEBUG 2024-04-11 02:52:46,299 INFO - Download file (size 504k) 2024-04-11 02:52:46,300 INFO 2024-04-11 02:52:46,300 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-11 02:52:46,300 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:46,601 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:46,601 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:46,601 DEBUG --- stdout --- 2024-04-11 02:52:46,601 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:46,601 DEBUG --- stderr --- 2024-04-11 02:52:46,601 DEBUG source : /results/orders/authn-users-100/stdout.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-11 02:52:46,602 INFO 2024-04-11 02:52:46,602 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-11 02:52:46,602 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:46,912 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:46,912 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:46,912 DEBUG --- stdout --- 2024-04-11 02:52:46,912 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:46,912 DEBUG --- stderr --- 2024-04-11 02:52:46,912 DEBUG 2024-04-11 02:52:46,913 INFO 2024-04-11 02:52:46,913 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/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-11 02:52:46,913 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 02:52:47,996 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:47,996 DEBUG --- stdout --- 2024-04-11 02:52:47,996 DEBUG tar: Removing leading `/' from member names 2024-04-11 02:52:47,996 DEBUG --- stderr --- 2024-04-11 02:52:47,996 DEBUG ________________________________________________________________________________ [2024-04-11 02:52:47] authn-users-100 show_cmd : Display order command ________________________________________________________________________________ 2024-04-11 02:52:47,997 INFO 2024-04-11 02:52:47,997 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-11 02:52:47,997 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:48,312 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:48,312 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:48,312 DEBUG --- stdout --- 2024-04-11 02:52:48,312 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:48,312 DEBUG --- stderr --- 2024-04-11 02:52:48,312 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 02:52:48,312 INFO 2024-04-11 02:52:48,312 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-11 02:52:48,312 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:48,626 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:48,626 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:48,626 DEBUG --- stdout --- 2024-04-11 02:52:48,626 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:48,626 DEBUG --- stderr --- 2024-04-11 02:52:48,626 DEBUG 2024-04-11 02:52:48,627 INFO 2024-04-11 02:52:48,627 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --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-11 02:52:48,627 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 02:52:49,459 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 02:52:49,459 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:49,459 DEBUG --- stdout --- 2024-04-11 02:52:49,459 DEBUG /results/orders/authn-users-100/cmd.txt file found 2024-04-11 02:52:49,459 DEBUG --- stderr --- 2024-04-11 02:52:49,459 DEBUG 2024-04-11 02:52:49,460 INFO 2024-04-11 02:52:49,460 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-11 02:52:49,460 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:49,779 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:49,779 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:49,779 DEBUG --- stdout --- 2024-04-11 02:52:49,779 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:49,779 DEBUG --- stderr --- 2024-04-11 02:52:49,779 DEBUG 2024-04-11 02:52:49,779 INFO 2024-04-11 02:52:49,780 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-100/cmd.txt 2024-04-11 02:52:50,621 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 02:52:50,621 DEBUG --- stdout --- 2024-04-11 02:52:50,621 DEBUG 4 /results/orders/authn-users-100/cmd.txt 2024-04-11 02:52:50,621 DEBUG --- stderr --- 2024-04-11 02:52:50,621 DEBUG 2024-04-11 02:52:50,621 INFO - Download file (size 4k) 2024-04-11 02:52:50,622 INFO 2024-04-11 02:52:50,622 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-11 02:52:50,622 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:50,947 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:50,947 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:50,947 DEBUG --- stdout --- 2024-04-11 02:52:50,948 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:50,948 DEBUG --- stderr --- 2024-04-11 02:52:50,948 DEBUG source : /results/orders/authn-users-100/cmd.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-11 02:52:50,948 INFO 2024-04-11 02:52:50,948 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-11 02:52:50,948 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:51,301 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:51,301 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:51,301 DEBUG --- stdout --- 2024-04-11 02:52:51,301 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:51,301 DEBUG --- stderr --- 2024-04-11 02:52:51,301 DEBUG 2024-04-11 02:52:51,302 INFO 2024-04-11 02:52:51,302 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/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-11 02:52:51,302 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 02:52:52,115 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:52,116 DEBUG --- stdout --- 2024-04-11 02:52:52,116 DEBUG tar: Removing leading `/' from member names 2024-04-11 02:52:52,116 DEBUG --- stderr --- 2024-04-11 02:52:52,116 DEBUG 2024-04-11 02:52:52,116 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-11 02:52:52,116 DEBUG ----- output ----- 2024-04-11 02:52:52,117 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-11 02:52:52,117 INFO ________________________________________________________________________________ [2024-04-11 02:52:52] authn-users-100 show_rc : Display rc order ________________________________________________________________________________ 2024-04-11 02:52:52,117 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-11 02:52:52,117 DEBUG ----- output ----- 2024-04-11 02:52:52,117 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-11 02:52:52,117 INFO ________________________________________________________________________________ [2024-04-11 02:52:52] authn-users-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-11 02:52:52,120 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt is 8754 lines): 2024-04-11 02:52:52,120 DEBUG ----- output ----- 2024-04-11 02:52:52,120 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-11 02:52:52,120 INFO 02:50:08.532 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-11 02:52:52,120 INFO 02:50:14.563 [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-11 02:52:52,120 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-11 02:52:52,120 INFO ^ 2024-04-11 02:52:52,120 INFO 02:50:14.566 [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-11 02:52:52,120 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-11 02:52:52,120 INFO ^ 2024-04-11 02:52:52,120 INFO 02:50:16.967 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-11 02:52:52,120 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-11 02:52:52,120 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-11 02:52:52,120 INFO 2024-04-11 02:52:52,120 INFO ================================================================================ 2024-04-11 02:52:52,120 INFO 2024-04-11 02:50:25 5s elapsed 2024-04-11 02:52:52,120 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,120 INFO > Global (OK=41 KO=0 ) 2024-04-11 02:52:52,121 INFO > userRestLogin (OK=41 KO=0 ) 2024-04-11 02:52:52,121 INFO 2024-04-11 02:52:52,121 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,121 INFO [- ] 0% 2024-04-11 02:52:52,121 INFO waiting: 361431 / active: 1 / done: 41 2024-04-11 02:52:52,121 INFO ================================================================================ 2024-04-11 02:52:52,121 INFO 2024-04-11 02:52:52,121 INFO 2024-04-11 02:52:52,121 INFO ================================================================================ 2024-04-11 02:52:52,121 INFO 2024-04-11 02:50:30 10s elapsed 2024-04-11 02:52:52,121 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,121 INFO > Global (OK=166 KO=0 ) 2024-04-11 02:52:52,121 INFO > userRestLogin (OK=166 KO=0 ) 2024-04-11 02:52:52,121 INFO 2024-04-11 02:52:52,121 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,121 INFO [- ] 0% 2024-04-11 02:52:52,121 INFO waiting: 361306 / active: 1 / done: 166 2024-04-11 02:52:52,121 INFO ================================================================================ 2024-04-11 02:52:52,121 INFO 2024-04-11 02:52:52,121 INFO 2024-04-11 02:52:52,121 INFO ================================================================================ 2024-04-11 02:52:52,121 INFO 2024-04-11 02:50:35 15s elapsed 2024-04-11 02:52:52,121 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,121 INFO > Global (OK=375 KO=0 ) 2024-04-11 02:52:52,121 INFO > userRestLogin (OK=375 KO=0 ) 2024-04-11 02:52:52,121 INFO 2024-04-11 02:52:52,121 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,122 INFO [- ] 0% 2024-04-11 02:52:52,122 INFO waiting: 361097 / active: 1 / done: 375 2024-04-11 02:52:52,122 INFO ================================================================================ 2024-04-11 02:52:52,122 INFO 2024-04-11 02:52:52,122 INFO 2024-04-11 02:52:52,122 INFO ================================================================================ 2024-04-11 02:52:52,122 INFO 2024-04-11 02:50:40 20s elapsed 2024-04-11 02:52:52,122 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,122 INFO > Global (OK=666 KO=0 ) 2024-04-11 02:52:52,122 INFO > userRestLogin (OK=666 KO=0 ) 2024-04-11 02:52:52,122 INFO 2024-04-11 02:52:52,122 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,122 INFO [- ] 0% 2024-04-11 02:52:52,122 INFO waiting: 360806 / active: 1 / done: 666 2024-04-11 02:52:52,122 INFO ================================================================================ 2024-04-11 02:52:52,122 INFO 2024-04-11 02:52:52,122 INFO 2024-04-11 02:52:52,122 INFO ================================================================================ 2024-04-11 02:52:52,122 INFO 2024-04-11 02:50:45 25s elapsed 2024-04-11 02:52:52,122 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,122 INFO > Global (OK=1041 KO=0 ) 2024-04-11 02:52:52,122 INFO > userRestLogin (OK=1041 KO=0 ) 2024-04-11 02:52:52,122 INFO 2024-04-11 02:52:52,123 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,123 INFO [- ] 0% 2024-04-11 02:52:52,123 INFO waiting: 360431 / active: 1 / done: 1041 2024-04-11 02:52:52,123 INFO ================================================================================ 2024-04-11 02:52:52,123 INFO 2024-04-11 02:52:52,123 INFO 2024-04-11 02:52:52,123 INFO ================================================================================ 2024-04-11 02:52:52,123 INFO 2024-04-11 02:50:50 30s elapsed 2024-04-11 02:52:52,123 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,123 INFO > Global (OK=1499 KO=0 ) 2024-04-11 02:52:52,123 INFO > userRestLogin (OK=1499 KO=0 ) 2024-04-11 02:52:52,123 INFO 2024-04-11 02:52:52,123 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,123 INFO [- ] 0% 2024-04-11 02:52:52,123 INFO waiting: 359971 / active: 3 / done: 1499 2024-04-11 02:52:52,123 INFO ================================================================================ 2024-04-11 02:52:52,123 INFO 2024-04-11 02:52:52,123 INFO 2024-04-11 02:52:52,123 INFO ================================================================================ 2024-04-11 02:52:52,123 INFO 2024-04-11 02:50:55 35s elapsed 2024-04-11 02:52:52,123 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,123 INFO > Global (OK=1973 KO=0 ) 2024-04-11 02:52:52,123 INFO > userRestLogin (OK=1973 KO=0 ) 2024-04-11 02:52:52,123 INFO 2024-04-11 02:52:52,123 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,123 INFO [- ] 0% 2024-04-11 02:52:52,124 INFO waiting: 359497 / active: 3 / done: 1973 2024-04-11 02:52:52,124 INFO ================================================================================ 2024-04-11 02:52:52,124 INFO 2024-04-11 02:52:52,124 INFO 2024-04-11 02:52:52,124 INFO ================================================================================ 2024-04-11 02:52:52,124 INFO 2024-04-11 02:51:00 40s elapsed 2024-04-11 02:52:52,124 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,124 INFO > Global (OK=2494 KO=0 ) 2024-04-11 02:52:52,124 INFO [...] 2024-04-11 02:52:52,128 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,128 INFO > Global (OK=359034 KO=0 ) 2024-04-11 02:52:52,128 INFO > userRestLogin (OK=359034 KO=0 ) 2024-04-11 02:52:52,128 INFO 2024-04-11 02:52:52,128 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,128 INFO [#########################################################################-] 99% 2024-04-11 02:52:52,128 INFO waiting: 2437 / active: 2 / done: 359034 2024-04-11 02:52:52,128 INFO ================================================================================ 2024-04-11 02:52:52,128 INFO 2024-04-11 02:52:52,128 INFO 2024-04-11 02:52:52,128 INFO ================================================================================ 2024-04-11 02:52:52,128 INFO 2024-04-11 03:50:30 3610s elapsed 2024-04-11 02:52:52,128 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,128 INFO > Global (OK=359485 KO=0 ) 2024-04-11 02:52:52,128 INFO > userRestLogin (OK=359485 KO=0 ) 2024-04-11 02:52:52,128 INFO 2024-04-11 02:52:52,128 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,129 INFO [#########################################################################-] 99% 2024-04-11 02:52:52,129 INFO waiting: 1987 / active: 1 / done: 359485 2024-04-11 02:52:52,129 INFO ================================================================================ 2024-04-11 02:52:52,129 INFO 2024-04-11 02:52:52,129 INFO 2024-04-11 02:52:52,129 INFO ================================================================================ 2024-04-11 02:52:52,129 INFO 2024-04-11 03:50:35 3615s elapsed 2024-04-11 02:52:52,129 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,129 INFO > Global (OK=359976 KO=0 ) 2024-04-11 02:52:52,129 INFO > userRestLogin (OK=359976 KO=0 ) 2024-04-11 02:52:52,129 INFO 2024-04-11 02:52:52,129 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,129 INFO [######################################################################### ] 99% 2024-04-11 02:52:52,129 INFO waiting: 1497 / active: 0 / done: 359976 2024-04-11 02:52:52,129 INFO ================================================================================ 2024-04-11 02:52:52,129 INFO 2024-04-11 02:52:52,129 INFO 2024-04-11 02:52:52,131 INFO ================================================================================ 2024-04-11 02:52:52,132 INFO 2024-04-11 03:50:40 3620s elapsed 2024-04-11 02:52:52,132 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,132 INFO > Global (OK=360460 KO=0 ) 2024-04-11 02:52:52,132 INFO > userRestLogin (OK=360460 KO=0 ) 2024-04-11 02:52:52,132 INFO 2024-04-11 02:52:52,132 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,132 INFO [######################################################################### ] 99% 2024-04-11 02:52:52,132 INFO waiting: 1013 / active: 0 / done: 360460 2024-04-11 02:52:52,132 INFO ================================================================================ 2024-04-11 02:52:52,132 INFO 2024-04-11 02:52:52,132 INFO 2024-04-11 02:52:52,133 INFO ================================================================================ 2024-04-11 02:52:52,133 INFO 2024-04-11 03:50:45 3625s elapsed 2024-04-11 02:52:52,133 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,133 INFO > Global (OK=360944 KO=0 ) 2024-04-11 02:52:52,133 INFO > userRestLogin (OK=360944 KO=0 ) 2024-04-11 02:52:52,133 INFO 2024-04-11 02:52:52,133 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,133 INFO [######################################################################### ] 99% 2024-04-11 02:52:52,133 INFO waiting: 529 / active: 0 / done: 360944 2024-04-11 02:52:52,133 INFO ================================================================================ 2024-04-11 02:52:52,133 INFO 2024-04-11 02:52:52,133 INFO 2024-04-11 02:52:52,133 INFO ================================================================================ 2024-04-11 02:52:52,133 INFO 2024-04-11 03:50:50 3630s elapsed 2024-04-11 02:52:52,133 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,133 INFO > Global (OK=361472 KO=0 ) 2024-04-11 02:52:52,133 INFO > userRestLogin (OK=361472 KO=0 ) 2024-04-11 02:52:52,133 INFO 2024-04-11 02:52:52,133 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,133 INFO [#########################################################################-] 99% 2024-04-11 02:52:52,133 INFO waiting: 0 / active: 1 / done: 361472 2024-04-11 02:52:52,133 INFO ================================================================================ 2024-04-11 02:52:52,133 INFO 2024-04-11 02:52:52,133 INFO 2024-04-11 02:52:52,134 INFO ================================================================================ 2024-04-11 02:52:52,134 INFO 2024-04-11 03:50:50 3630s elapsed 2024-04-11 02:52:52,134 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 02:52:52,134 INFO > Global (OK=361473 KO=0 ) 2024-04-11 02:52:52,134 INFO > userRestLogin (OK=361473 KO=0 ) 2024-04-11 02:52:52,134 INFO 2024-04-11 02:52:52,134 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 02:52:52,134 INFO [##########################################################################]100% 2024-04-11 02:52:52,134 INFO waiting: 0 / active: 0 / done: 361473 2024-04-11 02:52:52,134 INFO ================================================================================ 2024-04-11 02:52:52,134 INFO 2024-04-11 02:52:52,134 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-11 02:52:52,134 INFO 2024-04-11 02:52:52,134 INFO ---------------------- 2024-04-11 02:52:52,134 INFO [11/Apr/2024 03:50:50] Create tar file authn-users-100.tar.gz 2024-04-11 02:52:52,134 INFO ---------------------- 2024-04-11 02:52:52,134 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-11 02:52:52,134 INFO RESULT_FOLDER=authn-users-100 2024-04-11 02:52:52,134 INFO SIMULATION_PATH=authn-users-100/loadtest-20240411015020120 2024-04-11 02:52:52,134 INFO PWD=/results/gatling/authn-users-100/loadtest-20240411015020120 2024-04-11 02:52:52,134 INFO [cmd] tar -czvf /results/gatling//authn-users-100.tar.gz --exclude=simulation.log * 2024-04-11 02:52:52,134 INFO /results/gatling 2024-04-11 02:52:52,134 INFO 2024-04-11 02:52:52,134 INFO ---------------------- 2024-04-11 02:52:52,135 INFO [11/Apr/2024 03:50:50] Update /results/gatling//latest 2024-04-11 02:52:52,135 INFO ---------------------- 2024-04-11 02:52:52,135 INFO 2024-04-11 02:52:52,135 INFO ---------------------- 2024-04-11 02:52:52,135 INFO [11/Apr/2024 03:50:50] Update /results/gatling//latest.tar.gz 2024-04-11 02:52:52,135 INFO ---------------------- 2024-04-11 02:52:52,135 INFO ________________________________________________________________________________ [2024-04-11 02:52:52] authn-users-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-11 02:52:52,135 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-11 02:52:52,135 DEBUG ----- output ----- 2024-04-11 02:52:52,135 DEBUG 2024-04-11 02:52:52,136 INFO ________________________________________________________________________________ [2024-04-11 02:52:52] 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-11 02:52:52] authn-users-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-11 02:52:52] authn-users-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped