--Task-- name: authn-users-100 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: [] preceding_task: None options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 100, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-19 05:47:10] authn-users-100 pre : N/A ________________________________________________________________________________ [2024-04-19 05:47:10] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-19 05:47:10] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-100/stdout.txt Pod log : /results/orders/authn-users-100 2024-04-19 05:47:10,789 INFO 2024-04-19 05:47:10,789 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 05:47:10,789 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 05:47:11,133 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 05:47:11,133 INFO [loop_until]: OK (rc = 0) 2024-04-19 05:47:11,133 DEBUG --- stdout --- 2024-04-19 05:47:11,133 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 05:47:11,133 DEBUG --- stderr --- 2024-04-19 05:47:11,133 DEBUG [2024-04-19 05:47:11] - INFO: Get overseer pod resources requests memory 2024-04-19 05:47:11,134 INFO 2024-04-19 05:47:11,134 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-5c67bf94db-77mh9 --output jsonpath={.spec.containers[].resources.requests.memory} 2024-04-19 05:47:11,134 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 05:47:11,460 INFO [loop_until]: OK (rc = 0) 2024-04-19 05:47:11,460 DEBUG --- stdout --- 2024-04-19 05:47:11,461 DEBUG 4Gi 2024-04-19 05:47:11,461 DEBUG --- stderr --- 2024-04-19 05:47:11,461 DEBUG ________________________________________________________________________________ [2024-04-19 05:47:11] authn-users-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-19 05:47:11,461 INFO 2024-04-19 05:47:11,461 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 05:47:11,461 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 05:47:11,765 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 05:47:11,765 INFO [loop_until]: OK (rc = 0) 2024-04-19 05:47:11,765 DEBUG --- stdout --- 2024-04-19 05:47:11,766 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 05:47:11,766 DEBUG --- stderr --- 2024-04-19 05:47:11,766 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "am.AMAuthN@medium" --results-folder /results/gatling/authn-users-100 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-19 05:47:11] authn-users-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-19 05:47:11,766 INFO 2024-04-19 05:47:11,766 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 05:47:11,766 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 05:47:12,074 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 05:47:12,074 INFO [loop_until]: OK (rc = 0) 2024-04-19 05:47:12,074 DEBUG --- stdout --- 2024-04-19 05:47:12,075 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 05:47:12,075 DEBUG --- stderr --- 2024-04-19 05:47:12,075 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/order.json { "name": "authn-users-100", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"am.AMAuthN@medium\" --results-folder /results/gatling/authn-users-100 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-100", "environment": { "JAVA_OPTS": "-Dam_host=xlou.iam.xlou-cdm.engineeringpit.com -DamadminPassword=**** -DamadminUsername=amadmin -Dduration=3600 -Dnum_users=1000000 -DtestCase=am.AMAuthN -DtestMode=open -Dthroughput=100 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-19 05:47:12] authn-users-100 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-04-19 05:47:12,076 INFO 2024-04-19 05:47:12,076 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-19 05:47:12,076 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 05:47:12,412 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 05:47:12,413 INFO [loop_until]: OK (rc = 0) 2024-04-19 05:47:12,413 DEBUG --- stdout --- 2024-04-19 05:47:12,413 DEBUG WAITING 2024-04-19 05:47:12,413 DEBUG --- stderr --- 2024-04-19 05:47:12,413 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 27 0 --:--:-- --:--:-- --:--:-- 27 ________________________________________________________________________________ [2024-04-19 05:47:12] authn-users-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-19 05:47:12,413 INFO 2024-04-19 05:47:12,413 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 05:47:12,413 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 05:47:12,731 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 05:47:12,731 INFO [loop_until]: OK (rc = 0) 2024-04-19 05:47:12,731 DEBUG --- stdout --- 2024-04-19 05:47:12,731 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 05:47:12,731 DEBUG --- stderr --- 2024-04-19 05:47:12,731 DEBUG 2024-04-19 05:47:12,732 INFO 2024-04-19 05:47:12,732 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/order.json overseer-0-5c67bf94db-77mh9:/results/orders/order.json 2024-04-19 05:47:12,732 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 05:47:14,262 INFO [loop_until]: OK (rc = 0) 2024-04-19 05:47:14,262 DEBUG --- stdout --- 2024-04-19 05:47:14,263 DEBUG 2024-04-19 05:47:14,263 DEBUG --- stderr --- 2024-04-19 05:47:14,263 DEBUG Order has been successfully forwarded to overseer [2024-04-19 05:47:14] - INFO: Check if Gatling simulation started successfully 2024-04-19 05:47:14,263 INFO 2024-04-19 05:47:14,263 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 05:47:14,263 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 05:47:14,565 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 05:47:14,565 INFO [loop_until]: OK (rc = 0) 2024-04-19 05:47:14,565 DEBUG --- stdout --- 2024-04-19 05:47:14,565 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 05:47:14,565 DEBUG --- stderr --- 2024-04-19 05:47:14,565 DEBUG 2024-04-19 05:47:14,565 INFO 2024-04-19 05:47:14,566 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 -- head -n 25 /results/orders/authn-users-100/stdout.txt | grep "started..." 2024-04-19 05:47:14,566 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 05:47:15,325 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-19 05:47:21,100 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-19 05:47:26,872 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-19 05:47:32,648 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-19 05:47:32,648 INFO [loop_until]: OK (rc = 0) 2024-04-19 05:47:32,648 DEBUG --- stdout --- 2024-04-19 05:47:32,648 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 06:47:17.188 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 06:47:23.254 [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"); ^ 06:47:23.257 [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"); ^ 06:47:25.594 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 05:47:32,648 DEBUG --- stderr --- 2024-04-19 05:47:32,648 DEBUG ________________________________________________________________________________ [2024-04-19 05:47:32] 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 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-19 06:49:43] authn-users-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-19 06:49:43,073 INFO 2024-04-19 06:49:43,074 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:43,074 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:43,427 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:43,427 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:43,427 DEBUG --- stdout --- 2024-04-19 06:49:43,427 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:43,427 DEBUG --- stderr --- 2024-04-19 06:49:43,427 DEBUG 2024-04-19 06:49:43,428 INFO 2024-04-19 06:49:43,428 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 -- rm -f /results/orders/order.json 2024-04-19 06:49:43,428 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 06:49:44,280 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:44,280 DEBUG --- stdout --- 2024-04-19 06:49:44,280 DEBUG 2024-04-19 06:49:44,280 DEBUG --- stderr --- 2024-04-19 06:49:44,280 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-19 06:49:44] authn-users-100 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-19 06:49:44] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/order.json 2024-04-19 06:49:44,282 INFO 2024-04-19 06:49:44,282 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:44,282 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:44,645 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:44,645 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:44,645 DEBUG --- stdout --- 2024-04-19 06:49:44,646 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:44,646 DEBUG --- stderr --- 2024-04-19 06:49:44,646 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 06:49:44,646 INFO 2024-04-19 06:49:44,646 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:44,646 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:45,024 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:45,024 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:45,024 DEBUG --- stdout --- 2024-04-19 06:49:45,024 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:45,025 DEBUG --- stderr --- 2024-04-19 06:49:45,025 DEBUG 2024-04-19 06:49:45,025 INFO 2024-04-19 06:49:45,025 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-100/rc.txt" ]; then echo "/results/orders/authn-users-100/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-100/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/rc.txt file found" 2024-04-19 06:49:45,025 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 06:49:45,897 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 06:49:45,898 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:45,899 DEBUG --- stdout --- 2024-04-19 06:49:45,899 DEBUG /results/orders/authn-users-100/rc.txt file found 2024-04-19 06:49:45,899 DEBUG --- stderr --- 2024-04-19 06:49:45,899 DEBUG 2024-04-19 06:49:45,900 INFO 2024-04-19 06:49:45,900 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:45,900 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:46,240 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:46,241 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:46,241 DEBUG --- stdout --- 2024-04-19 06:49:46,241 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:46,241 DEBUG --- stderr --- 2024-04-19 06:49:46,241 DEBUG 2024-04-19 06:49:46,241 INFO 2024-04-19 06:49:46,242 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-100/rc.txt 2024-04-19 06:49:47,125 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 06:49:47,125 DEBUG --- stdout --- 2024-04-19 06:49:47,125 DEBUG 4 /results/orders/authn-users-100/rc.txt 2024-04-19 06:49:47,125 DEBUG --- stderr --- 2024-04-19 06:49:47,125 DEBUG 2024-04-19 06:49:47,125 INFO - Download file (size 4k) 2024-04-19 06:49:47,125 INFO 2024-04-19 06:49:47,125 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:47,125 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:47,432 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:47,433 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:47,433 DEBUG --- stdout --- 2024-04-19 06:49:47,433 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:47,433 DEBUG --- stderr --- 2024-04-19 06:49:47,433 DEBUG source : /results/orders/authn-users-100/rc.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-19 06:49:47,433 INFO 2024-04-19 06:49:47,433 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:47,433 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:47,753 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:47,753 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:47,753 DEBUG --- stdout --- 2024-04-19 06:49:47,753 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:47,753 DEBUG --- stderr --- 2024-04-19 06:49:47,753 DEBUG 2024-04-19 06:49:47,754 INFO 2024-04-19 06:49:47,754 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-100/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-19 06:49:47,754 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 06:49:48,542 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:48,542 DEBUG --- stdout --- 2024-04-19 06:49:48,542 DEBUG tar: Removing leading `/' from member names 2024-04-19 06:49:48,542 DEBUG --- stderr --- 2024-04-19 06:49:48,542 DEBUG 2024-04-19 06:49:48,542 INFO 2024-04-19 06:49:48,543 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:48,543 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:48,845 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:48,845 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:48,845 DEBUG --- stdout --- 2024-04-19 06:49:48,845 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:48,845 DEBUG --- stderr --- 2024-04-19 06:49:48,845 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 06:49:48,846 INFO 2024-04-19 06:49:48,846 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:48,846 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:49,147 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:49,148 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:49,148 DEBUG --- stdout --- 2024-04-19 06:49:49,148 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:49,148 DEBUG --- stderr --- 2024-04-19 06:49:49,148 DEBUG 2024-04-19 06:49:49,148 INFO 2024-04-19 06:49:49,148 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-100/stderr.txt" ]; then echo "/results/orders/authn-users-100/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-100/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/stderr.txt file found" 2024-04-19 06:49:49,149 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 06:49:50,007 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 06:49:50,008 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:50,008 DEBUG --- stdout --- 2024-04-19 06:49:50,008 DEBUG /results/orders/authn-users-100/stderr.txt file found 2024-04-19 06:49:50,008 DEBUG --- stderr --- 2024-04-19 06:49:50,008 DEBUG 2024-04-19 06:49:50,008 INFO 2024-04-19 06:49:50,008 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:50,008 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:50,330 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:50,331 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:50,331 DEBUG --- stdout --- 2024-04-19 06:49:50,331 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:50,331 DEBUG --- stderr --- 2024-04-19 06:49:50,331 DEBUG 2024-04-19 06:49:50,331 INFO 2024-04-19 06:49:50,332 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-100/stderr.txt 2024-04-19 06:49:51,181 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 06:49:51,181 DEBUG --- stdout --- 2024-04-19 06:49:51,182 DEBUG 0 /results/orders/authn-users-100/stderr.txt 2024-04-19 06:49:51,182 DEBUG --- stderr --- 2024-04-19 06:49:51,182 DEBUG 2024-04-19 06:49:51,182 INFO - Download file (size 0k) 2024-04-19 06:49:51,183 INFO 2024-04-19 06:49:51,183 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:51,183 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:51,513 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:51,514 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:51,514 DEBUG --- stdout --- 2024-04-19 06:49:51,514 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:51,514 DEBUG --- stderr --- 2024-04-19 06:49:51,514 DEBUG source : /results/orders/authn-users-100/stderr.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-19 06:49:51,515 INFO 2024-04-19 06:49:51,515 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:51,515 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:51,854 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:51,854 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:51,854 DEBUG --- stdout --- 2024-04-19 06:49:51,854 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:51,854 DEBUG --- stderr --- 2024-04-19 06:49:51,854 DEBUG 2024-04-19 06:49:51,855 INFO 2024-04-19 06:49:51,855 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-100/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-19 06:49:51,855 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 06:49:52,615 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:52,615 DEBUG --- stdout --- 2024-04-19 06:49:52,615 DEBUG tar: Removing leading `/' from member names 2024-04-19 06:49:52,615 DEBUG --- stderr --- 2024-04-19 06:49:52,615 DEBUG 2024-04-19 06:49:52,616 INFO 2024-04-19 06:49:52,616 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:52,616 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:52,940 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:52,940 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:52,940 DEBUG --- stdout --- 2024-04-19 06:49:52,940 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:52,940 DEBUG --- stderr --- 2024-04-19 06:49:52,940 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 06:49:52,941 INFO 2024-04-19 06:49:52,941 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:52,941 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:53,258 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:53,258 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:53,258 DEBUG --- stdout --- 2024-04-19 06:49:53,259 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:53,259 DEBUG --- stderr --- 2024-04-19 06:49:53,259 DEBUG 2024-04-19 06:49:53,259 INFO 2024-04-19 06:49:53,259 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-100/stdout.txt" ]; then echo "/results/orders/authn-users-100/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-100/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/stdout.txt file found" 2024-04-19 06:49:53,259 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 06:49:54,090 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 06:49:54,090 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:54,090 DEBUG --- stdout --- 2024-04-19 06:49:54,090 DEBUG /results/orders/authn-users-100/stdout.txt file found 2024-04-19 06:49:54,091 DEBUG --- stderr --- 2024-04-19 06:49:54,091 DEBUG 2024-04-19 06:49:54,091 INFO 2024-04-19 06:49:54,091 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:54,091 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:54,397 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:54,397 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:54,397 DEBUG --- stdout --- 2024-04-19 06:49:54,398 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:54,398 DEBUG --- stderr --- 2024-04-19 06:49:54,398 DEBUG 2024-04-19 06:49:54,398 INFO 2024-04-19 06:49:54,398 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-100/stdout.txt 2024-04-19 06:49:55,223 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 06:49:55,224 DEBUG --- stdout --- 2024-04-19 06:49:55,224 DEBUG 504 /results/orders/authn-users-100/stdout.txt 2024-04-19 06:49:55,224 DEBUG --- stderr --- 2024-04-19 06:49:55,224 DEBUG 2024-04-19 06:49:55,224 INFO - Download file (size 504k) 2024-04-19 06:49:55,224 INFO 2024-04-19 06:49:55,225 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:55,225 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:55,548 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:55,549 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:55,549 DEBUG --- stdout --- 2024-04-19 06:49:55,549 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:55,549 DEBUG --- stderr --- 2024-04-19 06:49:55,549 DEBUG source : /results/orders/authn-users-100/stdout.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-19 06:49:55,549 INFO 2024-04-19 06:49:55,549 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:55,549 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:55,867 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:55,867 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:55,867 DEBUG --- stdout --- 2024-04-19 06:49:55,868 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:55,868 DEBUG --- stderr --- 2024-04-19 06:49:55,868 DEBUG 2024-04-19 06:49:55,868 INFO 2024-04-19 06:49:55,868 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-100/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-19 06:49:55,868 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 06:49:56,953 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:56,953 DEBUG --- stdout --- 2024-04-19 06:49:56,953 DEBUG tar: Removing leading `/' from member names 2024-04-19 06:49:56,953 DEBUG --- stderr --- 2024-04-19 06:49:56,953 DEBUG ________________________________________________________________________________ [2024-04-19 06:49:56] authn-users-100 show_cmd : Display order command ________________________________________________________________________________ 2024-04-19 06:49:56,954 INFO 2024-04-19 06:49:56,955 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:56,955 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:57,283 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:57,284 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:57,284 DEBUG --- stdout --- 2024-04-19 06:49:57,284 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:57,284 DEBUG --- stderr --- 2024-04-19 06:49:57,284 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-19 06:49:57,284 INFO 2024-04-19 06:49:57,284 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:57,284 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:57,637 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:57,638 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:57,638 DEBUG --- stdout --- 2024-04-19 06:49:57,638 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:57,638 DEBUG --- stderr --- 2024-04-19 06:49:57,638 DEBUG 2024-04-19 06:49:57,638 INFO 2024-04-19 06:49:57,638 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-100/cmd.txt" ]; then echo "/results/orders/authn-users-100/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-100/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/cmd.txt file found" 2024-04-19 06:49:57,638 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 06:49:58,533 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 06:49:58,533 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:58,533 DEBUG --- stdout --- 2024-04-19 06:49:58,533 DEBUG /results/orders/authn-users-100/cmd.txt file found 2024-04-19 06:49:58,533 DEBUG --- stderr --- 2024-04-19 06:49:58,534 DEBUG 2024-04-19 06:49:58,534 INFO 2024-04-19 06:49:58,534 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:49:58,534 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:49:58,915 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:49:58,915 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:49:58,915 DEBUG --- stdout --- 2024-04-19 06:49:58,915 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:49:58,915 DEBUG --- stderr --- 2024-04-19 06:49:58,915 DEBUG 2024-04-19 06:49:58,916 INFO 2024-04-19 06:49:58,916 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5c67bf94db-77mh9 --stdin -- du -k /results/orders/authn-users-100/cmd.txt 2024-04-19 06:49:59,757 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-19 06:49:59,757 DEBUG --- stdout --- 2024-04-19 06:49:59,758 DEBUG 4 /results/orders/authn-users-100/cmd.txt 2024-04-19 06:49:59,758 DEBUG --- stderr --- 2024-04-19 06:49:59,758 DEBUG 2024-04-19 06:49:59,758 INFO - Download file (size 4k) 2024-04-19 06:49:59,758 INFO 2024-04-19 06:49:59,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-19 06:49:59,758 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:50:00,079 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:50:00,079 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:50:00,079 DEBUG --- stdout --- 2024-04-19 06:50:00,079 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:50:00,079 DEBUG --- stderr --- 2024-04-19 06:50:00,079 DEBUG source : /results/orders/authn-users-100/cmd.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-19 06:50:00,079 INFO 2024-04-19 06:50:00,079 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 06:50:00,080 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 06:50:00,418 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 06:50:00,419 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:50:00,419 DEBUG --- stdout --- 2024-04-19 06:50:00,419 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 06:50:00,419 DEBUG --- stderr --- 2024-04-19 06:50:00,419 DEBUG 2024-04-19 06:50:00,419 INFO 2024-04-19 06:50:00,419 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5c67bf94db-77mh9:/results/orders/authn-users-100/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-19 06:50:00,419 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-19 06:50:01,183 INFO [loop_until]: OK (rc = 0) 2024-04-19 06:50:01,183 DEBUG --- stdout --- 2024-04-19 06:50:01,183 DEBUG tar: Removing leading `/' from member names 2024-04-19 06:50:01,183 DEBUG --- stderr --- 2024-04-19 06:50:01,183 DEBUG 2024-04-19 06:50:01,184 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt is small (1 lines), printing all lines: 2024-04-19 06:50:01,184 DEBUG ----- output ----- 2024-04-19 06:50:01,184 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "am.AMAuthN@medium" --results-folder /results/gatling/authn-users-100 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-19 06:50:01,184 INFO ________________________________________________________________________________ [2024-04-19 06:50:01] authn-users-100 show_rc : Display rc order ________________________________________________________________________________ 2024-04-19 06:50:01,184 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt is small (1 lines), printing all lines: 2024-04-19 06:50:01,184 DEBUG ----- output ----- 2024-04-19 06:50:01,184 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-19 06:50:01,184 INFO ________________________________________________________________________________ [2024-04-19 06:50:01] authn-users-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-19 06:50:01,186 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt is 8742 lines): 2024-04-19 06:50:01,186 DEBUG ----- output ----- 2024-04-19 06:50:01,186 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-19 06:50:01,186 INFO 06:47:17.188 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-19 06:50:01,186 INFO 06:47:23.254 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-19 06:50:01,186 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-19 06:50:01,186 INFO ^ 2024-04-19 06:50:01,186 INFO 06:47:23.257 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-19 06:50:01,186 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-19 06:50:01,186 INFO ^ 2024-04-19 06:50:01,186 INFO 06:47:25.594 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-19 06:50:01,186 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-19 06:50:01,186 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 06:50:01,186 INFO 2024-04-19 06:50:01,186 INFO ================================================================================ 2024-04-19 06:50:01,186 INFO 2024-04-19 06:47:34 5s elapsed 2024-04-19 06:50:01,186 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,186 INFO > Global (OK=41 KO=0 ) 2024-04-19 06:50:01,186 INFO > userRestLogin (OK=41 KO=0 ) 2024-04-19 06:50:01,186 INFO 2024-04-19 06:50:01,186 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,186 INFO [- ] 0% 2024-04-19 06:50:01,186 INFO waiting: 362029 / active: 1 / done: 41 2024-04-19 06:50:01,186 INFO ================================================================================ 2024-04-19 06:50:01,187 INFO 2024-04-19 06:50:01,187 INFO 2024-04-19 06:50:01,187 INFO ================================================================================ 2024-04-19 06:50:01,187 INFO 2024-04-19 06:47:39 10s elapsed 2024-04-19 06:50:01,187 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,187 INFO > Global (OK=166 KO=0 ) 2024-04-19 06:50:01,187 INFO > userRestLogin (OK=166 KO=0 ) 2024-04-19 06:50:01,187 INFO 2024-04-19 06:50:01,187 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,187 INFO [- ] 0% 2024-04-19 06:50:01,187 INFO waiting: 361904 / active: 1 / done: 166 2024-04-19 06:50:01,187 INFO ================================================================================ 2024-04-19 06:50:01,187 INFO 2024-04-19 06:50:01,187 INFO 2024-04-19 06:50:01,187 INFO ================================================================================ 2024-04-19 06:50:01,187 INFO 2024-04-19 06:47:44 15s elapsed 2024-04-19 06:50:01,187 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,187 INFO > Global (OK=375 KO=0 ) 2024-04-19 06:50:01,187 INFO > userRestLogin (OK=375 KO=0 ) 2024-04-19 06:50:01,187 INFO 2024-04-19 06:50:01,187 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,187 INFO [- ] 0% 2024-04-19 06:50:01,187 INFO waiting: 361695 / active: 1 / done: 375 2024-04-19 06:50:01,187 INFO ================================================================================ 2024-04-19 06:50:01,187 INFO 2024-04-19 06:50:01,187 INFO 2024-04-19 06:50:01,187 INFO ================================================================================ 2024-04-19 06:50:01,187 INFO 2024-04-19 06:47:49 20s elapsed 2024-04-19 06:50:01,187 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,187 INFO > Global (OK=666 KO=0 ) 2024-04-19 06:50:01,187 INFO > userRestLogin (OK=666 KO=0 ) 2024-04-19 06:50:01,187 INFO 2024-04-19 06:50:01,187 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,187 INFO [- ] 0% 2024-04-19 06:50:01,187 INFO waiting: 361404 / active: 1 / done: 666 2024-04-19 06:50:01,187 INFO ================================================================================ 2024-04-19 06:50:01,187 INFO 2024-04-19 06:50:01,187 INFO 2024-04-19 06:50:01,187 INFO ================================================================================ 2024-04-19 06:50:01,187 INFO 2024-04-19 06:47:54 25s elapsed 2024-04-19 06:50:01,187 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,188 INFO > Global (OK=1041 KO=0 ) 2024-04-19 06:50:01,188 INFO > userRestLogin (OK=1041 KO=0 ) 2024-04-19 06:50:01,188 INFO 2024-04-19 06:50:01,188 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,188 INFO [- ] 0% 2024-04-19 06:50:01,188 INFO waiting: 361029 / active: 1 / done: 1041 2024-04-19 06:50:01,188 INFO ================================================================================ 2024-04-19 06:50:01,188 INFO 2024-04-19 06:50:01,188 INFO 2024-04-19 06:50:01,188 INFO ================================================================================ 2024-04-19 06:50:01,188 INFO 2024-04-19 06:47:59 30s elapsed 2024-04-19 06:50:01,188 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,188 INFO > Global (OK=1499 KO=0 ) 2024-04-19 06:50:01,188 INFO > userRestLogin (OK=1499 KO=0 ) 2024-04-19 06:50:01,188 INFO 2024-04-19 06:50:01,188 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,188 INFO [- ] 0% 2024-04-19 06:50:01,188 INFO waiting: 360571 / active: 1 / done: 1499 2024-04-19 06:50:01,188 INFO ================================================================================ 2024-04-19 06:50:01,188 INFO 2024-04-19 06:50:01,188 INFO 2024-04-19 06:50:01,188 INFO ================================================================================ 2024-04-19 06:50:01,188 INFO 2024-04-19 06:48:04 35s elapsed 2024-04-19 06:50:01,188 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,188 INFO > Global (OK=1978 KO=0 ) 2024-04-19 06:50:01,188 INFO > userRestLogin (OK=1978 KO=0 ) 2024-04-19 06:50:01,188 INFO 2024-04-19 06:50:01,188 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,188 INFO [- ] 0% 2024-04-19 06:50:01,188 INFO waiting: 360092 / active: 1 / done: 1978 2024-04-19 06:50:01,188 INFO ================================================================================ 2024-04-19 06:50:01,188 INFO 2024-04-19 06:50:01,188 INFO 2024-04-19 06:50:01,188 INFO ================================================================================ 2024-04-19 06:50:01,188 INFO 2024-04-19 06:48:09 40s elapsed 2024-04-19 06:50:01,188 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,188 INFO > Global (OK=2486 KO=0 ) 2024-04-19 06:50:01,188 INFO [...] 2024-04-19 06:50:01,191 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,191 INFO > Global (OK=359056 KO=0 ) 2024-04-19 06:50:01,191 INFO > userRestLogin (OK=359056 KO=0 ) 2024-04-19 06:50:01,191 INFO 2024-04-19 06:50:01,191 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,191 INFO [######################################################################### ] 99% 2024-04-19 06:50:01,191 INFO waiting: 3015 / active: 0 / done: 359056 2024-04-19 06:50:01,191 INFO ================================================================================ 2024-04-19 06:50:01,191 INFO 2024-04-19 06:50:01,191 INFO 2024-04-19 06:50:01,191 INFO ================================================================================ 2024-04-19 06:50:01,191 INFO 2024-04-19 07:47:34 3605s elapsed 2024-04-19 06:50:01,191 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,191 INFO > Global (OK=359565 KO=0 ) 2024-04-19 06:50:01,191 INFO > userRestLogin (OK=359565 KO=0 ) 2024-04-19 06:50:01,191 INFO 2024-04-19 06:50:01,191 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,191 INFO [#########################################################################-] 99% 2024-04-19 06:50:01,191 INFO waiting: 2504 / active: 2 / done: 359565 2024-04-19 06:50:01,191 INFO ================================================================================ 2024-04-19 06:50:01,191 INFO 2024-04-19 06:50:01,191 INFO 2024-04-19 06:50:01,191 INFO ================================================================================ 2024-04-19 06:50:01,191 INFO 2024-04-19 07:47:39 3610s elapsed 2024-04-19 06:50:01,191 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,191 INFO > Global (OK=360055 KO=0 ) 2024-04-19 06:50:01,191 INFO > userRestLogin (OK=360055 KO=0 ) 2024-04-19 06:50:01,191 INFO 2024-04-19 06:50:01,192 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,192 INFO [#########################################################################-] 99% 2024-04-19 06:50:01,192 INFO waiting: 2015 / active: 1 / done: 360055 2024-04-19 06:50:01,192 INFO ================================================================================ 2024-04-19 06:50:01,192 INFO 2024-04-19 06:50:01,192 INFO 2024-04-19 06:50:01,192 INFO ================================================================================ 2024-04-19 06:50:01,192 INFO 2024-04-19 07:47:44 3615s elapsed 2024-04-19 06:50:01,192 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,192 INFO > Global (OK=360539 KO=0 ) 2024-04-19 06:50:01,192 INFO > userRestLogin (OK=360539 KO=0 ) 2024-04-19 06:50:01,192 INFO 2024-04-19 06:50:01,192 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,192 INFO [#########################################################################-] 99% 2024-04-19 06:50:01,192 INFO waiting: 1529 / active: 3 / done: 360539 2024-04-19 06:50:01,192 INFO ================================================================================ 2024-04-19 06:50:01,192 INFO 2024-04-19 06:50:01,192 INFO 2024-04-19 06:50:01,192 INFO ================================================================================ 2024-04-19 06:50:01,192 INFO 2024-04-19 07:47:49 3620s elapsed 2024-04-19 06:50:01,192 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,192 INFO > Global (OK=361060 KO=0 ) 2024-04-19 06:50:01,192 INFO > userRestLogin (OK=361060 KO=0 ) 2024-04-19 06:50:01,192 INFO 2024-04-19 06:50:01,192 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,192 INFO [#########################################################################-] 99% 2024-04-19 06:50:01,192 INFO waiting: 1009 / active: 2 / done: 361060 2024-04-19 06:50:01,192 INFO ================================================================================ 2024-04-19 06:50:01,192 INFO 2024-04-19 06:50:01,192 INFO 2024-04-19 06:50:01,192 INFO ================================================================================ 2024-04-19 06:50:01,192 INFO 2024-04-19 07:47:54 3625s elapsed 2024-04-19 06:50:01,192 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,192 INFO > Global (OK=361572 KO=0 ) 2024-04-19 06:50:01,192 INFO > userRestLogin (OK=361572 KO=0 ) 2024-04-19 06:50:01,192 INFO 2024-04-19 06:50:01,192 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,192 INFO [#########################################################################-] 99% 2024-04-19 06:50:01,192 INFO waiting: 498 / active: 1 / done: 361572 2024-04-19 06:50:01,192 INFO ================================================================================ 2024-04-19 06:50:01,192 INFO 2024-04-19 06:50:01,193 INFO 2024-04-19 06:50:01,193 INFO ================================================================================ 2024-04-19 06:50:01,193 INFO 2024-04-19 07:47:59 3630s elapsed 2024-04-19 06:50:01,193 INFO ---- Requests ------------------------------------------------------------------ 2024-04-19 06:50:01,193 INFO > Global (OK=362071 KO=0 ) 2024-04-19 06:50:01,193 INFO > userRestLogin (OK=362071 KO=0 ) 2024-04-19 06:50:01,193 INFO 2024-04-19 06:50:01,193 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-19 06:50:01,193 INFO [##########################################################################]100% 2024-04-19 06:50:01,193 INFO waiting: 0 / active: 0 / done: 362071 2024-04-19 06:50:01,193 INFO ================================================================================ 2024-04-19 06:50:01,193 INFO 2024-04-19 06:50:01,193 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-19 06:50:01,193 INFO 2024-04-19 06:50:01,193 INFO ---------------------- 2024-04-19 06:50:01,193 INFO [19/Apr/2024 07:47:59] Create tar file authn-users-100.tar.gz 2024-04-19 06:50:01,193 INFO ---------------------- 2024-04-19 06:50:01,193 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-19 06:50:01,193 INFO RESULT_FOLDER=authn-users-100 2024-04-19 06:50:01,193 INFO SIMULATION_PATH=authn-users-100/loadtest-20240419054728603 2024-04-19 06:50:01,193 INFO PWD=/results/gatling/authn-users-100/loadtest-20240419054728603 2024-04-19 06:50:01,193 INFO [cmd] tar -czvf /results/gatling//authn-users-100.tar.gz --exclude=simulation.log * 2024-04-19 06:50:01,193 INFO /results/gatling 2024-04-19 06:50:01,193 INFO 2024-04-19 06:50:01,193 INFO ---------------------- 2024-04-19 06:50:01,193 INFO [19/Apr/2024 07:47:59] Update /results/gatling//latest 2024-04-19 06:50:01,193 INFO ---------------------- 2024-04-19 06:50:01,193 INFO 2024-04-19 06:50:01,193 INFO ---------------------- 2024-04-19 06:50:01,193 INFO [19/Apr/2024 07:47:59] Update /results/gatling//latest.tar.gz 2024-04-19 06:50:01,193 INFO ---------------------- 2024-04-19 06:50:01,193 INFO ________________________________________________________________________________ [2024-04-19 06:50:01] authn-users-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-19 06:50:01,194 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt is small (0 lines), printing all lines: 2024-04-19 06:50:01,194 DEBUG ----- output ----- 2024-04-19 06:50:01,194 DEBUG 2024-04-19 06:50:01,194 INFO ________________________________________________________________________________ [2024-04-19 06:50:01] authn-users-100 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-19 06:50:01] authn-users-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-19 06:50:01] authn-users-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped