--Task-- name: authn-users-4000 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: ['authn-users-2000'] preceding_task: authn-users-2000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 4000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-12 23:15:29] authn-users-4000 pre : N/A ________________________________________________________________________________ [2024-04-12 23:15:29] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-12 23:15:29] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-4000/stdout.txt Pod log : /results/orders/authn-users-4000 2024-04-12 23:15:29,437 INFO 2024-04-12 23:15:29,438 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 23:15:29,438 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:29,740 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:29,740 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:29,741 DEBUG --- stdout --- 2024-04-12 23:15:29,741 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:29,741 DEBUG --- stderr --- 2024-04-12 23:15:29,741 DEBUG ________________________________________________________________________________ [2024-04-12 23:15:29] authn-users-4000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-12 23:15:29,741 INFO 2024-04-12 23:15:29,741 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 23:15:29,741 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:30,045 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:30,046 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:30,046 DEBUG --- stdout --- 2024-04-12 23:15:30,046 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:30,046 DEBUG --- stderr --- 2024-04-12 23:15:30,046 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-4000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-12 23:15:30] authn-users-4000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-12 23:15:30,047 INFO 2024-04-12 23:15:30,047 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 23:15:30,047 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:30,341 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:30,341 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:30,341 DEBUG --- stdout --- 2024-04-12 23:15:30,342 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:30,342 DEBUG --- stderr --- 2024-04-12 23:15:30,342 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/order.json { "name": "authn-users-4000", "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-4000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-4000", "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=4000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-12 23:15:30] authn-users-4000 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-04-12 23:15:30,343 INFO 2024-04-12 23:15:30,343 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-12 23:15:30,343 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-12 23:15:30,582 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-12 23:15:30,583 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:30,583 DEBUG --- stdout --- 2024-04-12 23:15:30,583 DEBUG WAITING 2024-04-12 23:15:30,583 DEBUG --- stderr --- 2024-04-12 23:15:30,583 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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-12 23:15:30] authn-users-4000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-12 23:15:30,583 INFO 2024-04-12 23:15:30,583 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 23:15:30,583 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:30,881 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:30,882 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:30,882 DEBUG --- stdout --- 2024-04-12 23:15:30,882 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:30,882 DEBUG --- stderr --- 2024-04-12 23:15:30,882 DEBUG 2024-04-12 23:15:30,882 INFO 2024-04-12 23:15:30,882 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/03_authn-users-4000/order.json overseer-0-59d79dcbf-spbbl:/results/orders/order.json 2024-04-12 23:15:30,882 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-12 23:15:32,251 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:32,251 DEBUG --- stdout --- 2024-04-12 23:15:32,251 DEBUG 2024-04-12 23:15:32,251 DEBUG --- stderr --- 2024-04-12 23:15:32,251 DEBUG Order has been successfully forwarded to overseer [2024-04-12 23:15:32] - INFO: Check if Gatling simulation started successfully 2024-04-12 23:15:32,251 INFO 2024-04-12 23:15:32,251 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-12 23:15:32,251 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-12 23:15:32,551 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-12 23:15:32,551 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:32,551 DEBUG --- stdout --- 2024-04-12 23:15:32,551 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-12 23:15:32,551 DEBUG --- stderr --- 2024-04-12 23:15:32,551 DEBUG 2024-04-12 23:15:32,552 INFO 2024-04-12 23:15:32,552 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl -- head -n 25 /results/orders/authn-users-4000/stdout.txt | grep "started..." 2024-04-12 23:15:32,552 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-12 23:15:33,303 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-12 23:15:39,069 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-12 23:15:44,833 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-12 23:15:44,833 INFO [loop_until]: OK (rc = 0) 2024-04-12 23:15:44,833 DEBUG --- stdout --- 2024-04-12 23:15:44,833 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Simulation com.forgerock.gatling.Loadtest started... ================================================================================ 2024-04-13 00:15:44 5s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=1662 KO=0 ) > userRestLogin (OK=1662 KO=0 ) ---- am.AMAuthN ---------------------------------------------------------------- [- ] 0% waiting: 14450745 / active: 9 / done: 1662 ================================================================================ 2024-04-12 23:15:44,833 DEBUG --- stderr --- 2024-04-12 23:15:44,833 DEBUG ________________________________________________________________________________ [2024-04-12 23:15:44] authn-users-4000 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 06s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 07s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 08s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 09s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 10s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-13 00:17:55] authn-users-4000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-13 00:17:55,520 INFO 2024-04-13 00:17:55,520 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-13 00:17:55,521 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:17:55,861 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:17:55,861 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:17:55,861 DEBUG --- stdout --- 2024-04-13 00:17:55,861 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:17:55,861 DEBUG --- stderr --- 2024-04-13 00:17:55,861 DEBUG 2024-04-13 00:17:55,861 INFO 2024-04-13 00:17:55,862 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl -- rm -f /results/orders/order.json 2024-04-13 00:17:55,862 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 00:17:56,662 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:17:56,662 DEBUG --- stdout --- 2024-04-13 00:17:56,662 DEBUG 2024-04-13 00:17:56,662 DEBUG --- stderr --- 2024-04-13 00:17:56,662 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-13 00:17:56] authn-users-4000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-13 00:17:56] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/order.json 2024-04-13 00:17:56,663 INFO 2024-04-13 00:17:56,663 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-13 00:17:56,663 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:17:56,973 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:17:56,973 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:17:56,973 DEBUG --- stdout --- 2024-04-13 00:17:56,973 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:17:56,973 DEBUG --- stderr --- 2024-04-13 00:17:56,973 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 00:17:56,973 INFO 2024-04-13 00:17:56,973 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-13 00:17:56,973 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:17:57,282 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:17:57,283 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:17:57,283 DEBUG --- stdout --- 2024-04-13 00:17:57,283 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:17:57,283 DEBUG --- stderr --- 2024-04-13 00:17:57,283 DEBUG 2024-04-13 00:17:57,284 INFO 2024-04-13 00:17:57,284 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4000/rc.txt" ]; then echo "/results/orders/authn-users-4000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-4000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4000/rc.txt file found" 2024-04-13 00:17:57,284 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 00:17:58,129 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 00:17:58,129 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:17:58,129 DEBUG --- stdout --- 2024-04-13 00:17:58,129 DEBUG /results/orders/authn-users-4000/rc.txt file found 2024-04-13 00:17:58,129 DEBUG --- stderr --- 2024-04-13 00:17:58,129 DEBUG 2024-04-13 00:17:58,129 INFO 2024-04-13 00:17:58,129 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-13 00:17:58,129 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:17:58,456 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:17:58,456 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:17:58,456 DEBUG --- stdout --- 2024-04-13 00:17:58,456 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:17:58,456 DEBUG --- stderr --- 2024-04-13 00:17:58,456 DEBUG 2024-04-13 00:17:58,456 INFO 2024-04-13 00:17:58,457 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-4000/rc.txt 2024-04-13 00:17:59,311 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 00:17:59,311 DEBUG --- stdout --- 2024-04-13 00:17:59,311 DEBUG 4 /results/orders/authn-users-4000/rc.txt 2024-04-13 00:17:59,311 DEBUG --- stderr --- 2024-04-13 00:17:59,311 DEBUG 2024-04-13 00:17:59,311 INFO - Download file (size 4k) 2024-04-13 00:17:59,312 INFO 2024-04-13 00:17:59,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-13 00:17:59,312 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:17:59,614 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:17:59,614 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:17:59,614 DEBUG --- stdout --- 2024-04-13 00:17:59,614 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:17:59,614 DEBUG --- stderr --- 2024-04-13 00:17:59,614 DEBUG source : /results/orders/authn-users-4000/rc.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/rc.txt 2024-04-13 00:17:59,614 INFO 2024-04-13 00:17:59,614 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-13 00:17:59,614 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:17:59,916 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:17:59,916 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:17:59,916 DEBUG --- stdout --- 2024-04-13 00:17:59,916 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:17:59,916 DEBUG --- stderr --- 2024-04-13 00:17:59,916 DEBUG 2024-04-13 00:17:59,917 INFO 2024-04-13 00:17:59,917 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-4000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/rc.txt 2024-04-13 00:17:59,917 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 00:18:00,692 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:00,692 DEBUG --- stdout --- 2024-04-13 00:18:00,692 DEBUG tar: Removing leading `/' from member names 2024-04-13 00:18:00,692 DEBUG --- stderr --- 2024-04-13 00:18:00,692 DEBUG 2024-04-13 00:18:00,693 INFO 2024-04-13 00:18:00,693 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-13 00:18:00,693 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:01,009 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:01,009 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:01,009 DEBUG --- stdout --- 2024-04-13 00:18:01,010 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:01,010 DEBUG --- stderr --- 2024-04-13 00:18:01,010 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 00:18:01,010 INFO 2024-04-13 00:18:01,010 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-13 00:18:01,010 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:01,352 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:01,352 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:01,352 DEBUG --- stdout --- 2024-04-13 00:18:01,352 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:01,352 DEBUG --- stderr --- 2024-04-13 00:18:01,352 DEBUG 2024-04-13 00:18:01,353 INFO 2024-04-13 00:18:01,353 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4000/stderr.txt" ]; then echo "/results/orders/authn-users-4000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-4000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4000/stderr.txt file found" 2024-04-13 00:18:01,353 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 00:18:02,196 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 00:18:02,196 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:02,197 DEBUG --- stdout --- 2024-04-13 00:18:02,197 DEBUG /results/orders/authn-users-4000/stderr.txt file found 2024-04-13 00:18:02,197 DEBUG --- stderr --- 2024-04-13 00:18:02,197 DEBUG 2024-04-13 00:18:02,197 INFO 2024-04-13 00:18:02,197 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-13 00:18:02,197 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:02,564 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:02,564 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:02,564 DEBUG --- stdout --- 2024-04-13 00:18:02,564 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:02,564 DEBUG --- stderr --- 2024-04-13 00:18:02,564 DEBUG 2024-04-13 00:18:02,564 INFO 2024-04-13 00:18:02,565 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-4000/stderr.txt 2024-04-13 00:18:03,403 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 00:18:03,405 DEBUG --- stdout --- 2024-04-13 00:18:03,405 DEBUG 0 /results/orders/authn-users-4000/stderr.txt 2024-04-13 00:18:03,405 DEBUG --- stderr --- 2024-04-13 00:18:03,405 DEBUG 2024-04-13 00:18:03,405 INFO - Download file (size 0k) 2024-04-13 00:18:03,406 INFO 2024-04-13 00:18:03,406 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-13 00:18:03,406 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:03,740 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:03,740 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:03,740 DEBUG --- stdout --- 2024-04-13 00:18:03,740 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:03,740 DEBUG --- stderr --- 2024-04-13 00:18:03,740 DEBUG source : /results/orders/authn-users-4000/stderr.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/stderr.txt 2024-04-13 00:18:03,741 INFO 2024-04-13 00:18:03,741 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-13 00:18:03,741 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:04,083 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:04,083 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:04,083 DEBUG --- stdout --- 2024-04-13 00:18:04,083 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:04,083 DEBUG --- stderr --- 2024-04-13 00:18:04,083 DEBUG 2024-04-13 00:18:04,083 INFO 2024-04-13 00:18:04,083 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-4000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/stderr.txt 2024-04-13 00:18:04,083 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 00:18:04,853 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:04,853 DEBUG --- stdout --- 2024-04-13 00:18:04,854 DEBUG tar: Removing leading `/' from member names 2024-04-13 00:18:04,854 DEBUG --- stderr --- 2024-04-13 00:18:04,854 DEBUG 2024-04-13 00:18:04,854 INFO 2024-04-13 00:18:04,854 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-13 00:18:04,854 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:05,161 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:05,161 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:05,161 DEBUG --- stdout --- 2024-04-13 00:18:05,161 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:05,161 DEBUG --- stderr --- 2024-04-13 00:18:05,161 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 00:18:05,162 INFO 2024-04-13 00:18:05,162 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-13 00:18:05,162 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:05,467 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:05,468 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:05,468 DEBUG --- stdout --- 2024-04-13 00:18:05,468 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:05,468 DEBUG --- stderr --- 2024-04-13 00:18:05,468 DEBUG 2024-04-13 00:18:05,468 INFO 2024-04-13 00:18:05,468 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4000/stdout.txt" ]; then echo "/results/orders/authn-users-4000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-4000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4000/stdout.txt file found" 2024-04-13 00:18:05,468 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 00:18:06,318 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 00:18:06,319 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:06,319 DEBUG --- stdout --- 2024-04-13 00:18:06,319 DEBUG /results/orders/authn-users-4000/stdout.txt file found 2024-04-13 00:18:06,319 DEBUG --- stderr --- 2024-04-13 00:18:06,319 DEBUG 2024-04-13 00:18:06,319 INFO 2024-04-13 00:18:06,319 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-13 00:18:06,319 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:06,636 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:06,636 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:06,636 DEBUG --- stdout --- 2024-04-13 00:18:06,636 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:06,636 DEBUG --- stderr --- 2024-04-13 00:18:06,636 DEBUG 2024-04-13 00:18:06,636 INFO 2024-04-13 00:18:06,637 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-4000/stdout.txt 2024-04-13 00:18:07,630 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 00:18:07,631 DEBUG --- stdout --- 2024-04-13 00:18:07,631 DEBUG 612 /results/orders/authn-users-4000/stdout.txt 2024-04-13 00:18:07,631 DEBUG --- stderr --- 2024-04-13 00:18:07,631 DEBUG 2024-04-13 00:18:07,631 INFO - Download file (size 612k) 2024-04-13 00:18:07,631 INFO 2024-04-13 00:18:07,631 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-13 00:18:07,631 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:07,939 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:07,939 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:07,939 DEBUG --- stdout --- 2024-04-13 00:18:07,939 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:07,940 DEBUG --- stderr --- 2024-04-13 00:18:07,940 DEBUG source : /results/orders/authn-users-4000/stdout.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/stdout.txt 2024-04-13 00:18:07,940 INFO 2024-04-13 00:18:07,940 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-13 00:18:07,940 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:08,253 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:08,253 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:08,253 DEBUG --- stdout --- 2024-04-13 00:18:08,253 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:08,253 DEBUG --- stderr --- 2024-04-13 00:18:08,253 DEBUG 2024-04-13 00:18:08,254 INFO 2024-04-13 00:18:08,254 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-4000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/stdout.txt 2024-04-13 00:18:08,254 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 00:18:09,369 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:09,369 DEBUG --- stdout --- 2024-04-13 00:18:09,370 DEBUG tar: Removing leading `/' from member names 2024-04-13 00:18:09,370 DEBUG --- stderr --- 2024-04-13 00:18:09,370 DEBUG ________________________________________________________________________________ [2024-04-13 00:18:09] authn-users-4000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-13 00:18:09,371 INFO 2024-04-13 00:18:09,371 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-13 00:18:09,371 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:09,717 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:09,718 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:09,718 DEBUG --- stdout --- 2024-04-13 00:18:09,718 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:09,718 DEBUG --- stderr --- 2024-04-13 00:18:09,718 DEBUG Checking pod: overseer-0-59d79dcbf-spbbl for file exists, if not wait. 2024-04-13 00:18:09,718 INFO 2024-04-13 00:18:09,719 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-13 00:18:09,719 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:10,074 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:10,074 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:10,074 DEBUG --- stdout --- 2024-04-13 00:18:10,074 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:10,074 DEBUG --- stderr --- 2024-04-13 00:18:10,074 DEBUG 2024-04-13 00:18:10,075 INFO 2024-04-13 00:18:10,075 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- bash -c 'if [ -f "/results/orders/authn-users-4000/cmd.txt" ]; then echo "/results/orders/authn-users-4000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-4000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-4000/cmd.txt file found" 2024-04-13 00:18:10,075 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-13 00:18:10,903 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-13 00:18:10,904 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:10,904 DEBUG --- stdout --- 2024-04-13 00:18:10,904 DEBUG /results/orders/authn-users-4000/cmd.txt file found 2024-04-13 00:18:10,904 DEBUG --- stderr --- 2024-04-13 00:18:10,904 DEBUG 2024-04-13 00:18:10,904 INFO 2024-04-13 00:18:10,904 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-13 00:18:10,904 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:11,213 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:11,213 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:11,213 DEBUG --- stdout --- 2024-04-13 00:18:11,214 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:11,214 DEBUG --- stderr --- 2024-04-13 00:18:11,214 DEBUG 2024-04-13 00:18:11,214 INFO 2024-04-13 00:18:11,214 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59d79dcbf-spbbl --stdin -- du -k /results/orders/authn-users-4000/cmd.txt 2024-04-13 00:18:12,035 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-13 00:18:12,035 DEBUG --- stdout --- 2024-04-13 00:18:12,035 DEBUG 4 /results/orders/authn-users-4000/cmd.txt 2024-04-13 00:18:12,035 DEBUG --- stderr --- 2024-04-13 00:18:12,035 DEBUG 2024-04-13 00:18:12,035 INFO - Download file (size 4k) 2024-04-13 00:18:12,035 INFO 2024-04-13 00:18:12,036 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-13 00:18:12,036 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:12,336 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:12,336 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:12,336 DEBUG --- stdout --- 2024-04-13 00:18:12,336 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:12,336 DEBUG --- stderr --- 2024-04-13 00:18:12,336 DEBUG source : /results/orders/authn-users-4000/cmd.txt @ overseer-0-59d79dcbf-spbbl destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/cmd.txt 2024-04-13 00:18:12,337 INFO 2024-04-13 00:18:12,337 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-13 00:18:12,337 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-13 00:18:12,641 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-13 00:18:12,642 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:12,642 DEBUG --- stdout --- 2024-04-13 00:18:12,642 DEBUG overseer-0-59d79dcbf-spbbl 2024-04-13 00:18:12,642 DEBUG --- stderr --- 2024-04-13 00:18:12,642 DEBUG 2024-04-13 00:18:12,642 INFO 2024-04-13 00:18:12,642 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59d79dcbf-spbbl:/results/orders/authn-users-4000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/cmd.txt 2024-04-13 00:18:12,642 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-13 00:18:13,401 INFO [loop_until]: OK (rc = 0) 2024-04-13 00:18:13,401 DEBUG --- stdout --- 2024-04-13 00:18:13,401 DEBUG tar: Removing leading `/' from member names 2024-04-13 00:18:13,401 DEBUG --- stderr --- 2024-04-13 00:18:13,401 DEBUG 2024-04-13 00:18:13,402 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/cmd.txt is small (1 lines), printing all lines: 2024-04-13 00:18:13,402 DEBUG ----- output ----- 2024-04-13 00:18:13,402 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-4000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-13 00:18:13,402 INFO ________________________________________________________________________________ [2024-04-13 00:18:13] authn-users-4000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-13 00:18:13,402 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/rc.txt is small (1 lines), printing all lines: 2024-04-13 00:18:13,402 DEBUG ----- output ----- 2024-04-13 00:18:13,402 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-13 00:18:13,402 INFO ________________________________________________________________________________ [2024-04-13 00:18:13] authn-users-4000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-13 00:18:13,408 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/03_authn-users-4000/stdout.txt is 10433 lines): 2024-04-13 00:18:13,408 DEBUG ----- output ----- 2024-04-13 00:18:13,408 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-13 00:18:13,408 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-13 00:18:13,408 INFO 2024-04-13 00:18:13,408 INFO ================================================================================ 2024-04-13 00:18:13,409 INFO 2024-04-13 00:15:44 5s elapsed 2024-04-13 00:18:13,409 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,409 INFO > Global (OK=1662 KO=0 ) 2024-04-13 00:18:13,409 INFO > userRestLogin (OK=1662 KO=0 ) 2024-04-13 00:18:13,409 INFO 2024-04-13 00:18:13,409 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,409 INFO [- ] 0% 2024-04-13 00:18:13,409 INFO waiting: 14450745 / active: 9 / done: 1662 2024-04-13 00:18:13,409 INFO ================================================================================ 2024-04-13 00:18:13,409 INFO 2024-04-13 00:18:13,409 INFO 2024-04-13 00:18:13,409 INFO ================================================================================ 2024-04-13 00:18:13,409 INFO 2024-04-13 00:15:49 10s elapsed 2024-04-13 00:18:13,409 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,409 INFO > Global (OK=6663 KO=0 ) 2024-04-13 00:18:13,409 INFO > userRestLogin (OK=6663 KO=0 ) 2024-04-13 00:18:13,409 INFO 2024-04-13 00:18:13,409 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,409 INFO [- ] 0% 2024-04-13 00:18:13,409 INFO waiting: 14445740 / active: 14 / done: 6662 2024-04-13 00:18:13,409 INFO ================================================================================ 2024-04-13 00:18:13,409 INFO 2024-04-13 00:18:13,409 INFO 2024-04-13 00:18:13,410 INFO ================================================================================ 2024-04-13 00:18:13,410 INFO 2024-04-13 00:15:54 15s elapsed 2024-04-13 00:18:13,410 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,410 INFO > Global (OK=14986 KO=0 ) 2024-04-13 00:18:13,410 INFO > userRestLogin (OK=14986 KO=0 ) 2024-04-13 00:18:13,410 INFO 2024-04-13 00:18:13,410 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,410 INFO [- ] 0% 2024-04-13 00:18:13,410 INFO waiting: 14437401 / active: 29 / done: 14986 2024-04-13 00:18:13,410 INFO ================================================================================ 2024-04-13 00:18:13,410 INFO 2024-04-13 00:18:13,410 INFO 2024-04-13 00:18:13,410 INFO ================================================================================ 2024-04-13 00:18:13,410 INFO 2024-04-13 00:15:59 20s elapsed 2024-04-13 00:18:13,410 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,410 INFO > Global (OK=26644 KO=0 ) 2024-04-13 00:18:13,410 INFO > userRestLogin (OK=26644 KO=0 ) 2024-04-13 00:18:13,410 INFO 2024-04-13 00:18:13,410 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,410 INFO [- ] 0% 2024-04-13 00:18:13,410 INFO waiting: 14425733 / active: 39 / done: 26644 2024-04-13 00:18:13,410 INFO ================================================================================ 2024-04-13 00:18:13,410 INFO 2024-04-13 00:18:13,411 INFO 2024-04-13 00:18:13,411 INFO ================================================================================ 2024-04-13 00:18:13,411 INFO 2024-04-13 00:16:04 25s elapsed 2024-04-13 00:18:13,411 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,411 INFO > Global (OK=41640 KO=0 ) 2024-04-13 00:18:13,411 INFO > userRestLogin (OK=41640 KO=0 ) 2024-04-13 00:18:13,411 INFO 2024-04-13 00:18:13,411 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,411 INFO [- ] 0% 2024-04-13 00:18:13,411 INFO waiting: 14410726 / active: 50 / done: 41640 2024-04-13 00:18:13,411 INFO ================================================================================ 2024-04-13 00:18:13,411 INFO 2024-04-13 00:18:13,411 INFO 2024-04-13 00:18:13,411 INFO ================================================================================ 2024-04-13 00:18:13,411 INFO 2024-04-13 00:16:09 30s elapsed 2024-04-13 00:18:13,411 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,411 INFO > Global (OK=59877 KO=0 ) 2024-04-13 00:18:13,411 INFO > userRestLogin (OK=59877 KO=0 ) 2024-04-13 00:18:13,411 INFO 2024-04-13 00:18:13,411 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,411 INFO [- ] 0% 2024-04-13 00:18:13,411 INFO waiting: 14392482 / active: 57 / done: 59877 2024-04-13 00:18:13,411 INFO ================================================================================ 2024-04-13 00:18:13,411 INFO 2024-04-13 00:18:13,411 INFO 2024-04-13 00:18:13,411 INFO ================================================================================ 2024-04-13 00:18:13,411 INFO 2024-04-13 00:16:14 35s elapsed 2024-04-13 00:18:13,411 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,411 INFO > Global (OK=79798 KO=0 ) 2024-04-13 00:18:13,412 INFO > userRestLogin (OK=79798 KO=0 ) 2024-04-13 00:18:13,412 INFO 2024-04-13 00:18:13,412 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,412 INFO [- ] 0% 2024-04-13 00:18:13,412 INFO waiting: 14372561 / active: 58 / done: 79797 2024-04-13 00:18:13,412 INFO ================================================================================ 2024-04-13 00:18:13,412 INFO 2024-04-13 00:18:13,412 INFO 2024-04-13 00:18:13,412 INFO ================================================================================ 2024-04-13 00:18:13,412 INFO 2024-04-13 00:16:19 40s elapsed 2024-04-13 00:18:13,412 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,412 INFO > Global (OK=99544 KO=0 ) 2024-04-13 00:18:13,412 INFO > userRestLogin (OK=99544 KO=0 ) 2024-04-13 00:18:13,412 INFO 2024-04-13 00:18:13,412 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,412 INFO [- ] 0% 2024-04-13 00:18:13,412 INFO waiting: 14352814 / active: 59 / done: 99543 2024-04-13 00:18:13,412 INFO ================================================================================ 2024-04-13 00:18:13,412 INFO 2024-04-13 00:18:13,412 INFO 2024-04-13 00:18:13,412 INFO ================================================================================ 2024-04-13 00:18:13,412 INFO [...] 2024-04-13 00:18:13,418 INFO 2024-04-13 00:18:13,418 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,418 INFO [#########################################################################-] 99% 2024-04-13 00:18:13,418 INFO waiting: 79986 / active: 59 / done: 14372371 2024-04-13 00:18:13,418 INFO ================================================================================ 2024-04-13 00:18:13,418 INFO 2024-04-13 00:18:13,418 INFO 2024-04-13 00:18:13,418 INFO ================================================================================ 2024-04-13 00:18:13,419 INFO 2024-04-13 01:15:54 3615s elapsed 2024-04-13 00:18:13,419 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,419 INFO > Global (OK=14392268 KO=1 ) 2024-04-13 00:18:13,419 INFO > userRestLogin (OK=14392268 KO=1 ) 2024-04-13 00:18:13,419 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 00:18:13,419 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-13 00:18:13,419 INFO ), but actually found 502 2024-04-13 00:18:13,419 INFO 2024-04-13 00:18:13,419 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,419 INFO [#########################################################################-] 99% 2024-04-13 00:18:13,419 INFO waiting: 60082 / active: 65 / done: 14392269 2024-04-13 00:18:13,419 INFO ================================================================================ 2024-04-13 00:18:13,419 INFO 2024-04-13 00:18:13,419 INFO 2024-04-13 00:18:13,419 INFO ================================================================================ 2024-04-13 00:18:13,419 INFO 2024-04-13 01:15:59 3620s elapsed 2024-04-13 00:18:13,419 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,419 INFO > Global (OK=14412439 KO=1 ) 2024-04-13 00:18:13,419 INFO > userRestLogin (OK=14412439 KO=1 ) 2024-04-13 00:18:13,420 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 00:18:13,420 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-13 00:18:13,420 INFO ), but actually found 502 2024-04-13 00:18:13,420 INFO 2024-04-13 00:18:13,420 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,420 INFO [#########################################################################-] 99% 2024-04-13 00:18:13,420 INFO waiting: 39895 / active: 81 / done: 14412440 2024-04-13 00:18:13,420 INFO ================================================================================ 2024-04-13 00:18:13,420 INFO 2024-04-13 00:18:13,420 INFO 2024-04-13 00:18:13,420 INFO ================================================================================ 2024-04-13 00:18:13,421 INFO 2024-04-13 01:16:04 3625s elapsed 2024-04-13 00:18:13,421 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,421 INFO > Global (OK=14432465 KO=1 ) 2024-04-13 00:18:13,421 INFO > userRestLogin (OK=14432465 KO=1 ) 2024-04-13 00:18:13,421 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 00:18:13,421 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-13 00:18:13,421 INFO ), but actually found 502 2024-04-13 00:18:13,421 INFO 2024-04-13 00:18:13,421 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,421 INFO [#########################################################################-] 99% 2024-04-13 00:18:13,421 INFO waiting: 19877 / active: 75 / done: 14432464 2024-04-13 00:18:13,421 INFO ================================================================================ 2024-04-13 00:18:13,421 INFO 2024-04-13 00:18:13,421 INFO 2024-04-13 00:18:13,421 INFO ================================================================================ 2024-04-13 00:18:13,421 INFO 2024-04-13 01:16:09 3630s elapsed 2024-04-13 00:18:13,421 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,421 INFO > Global (OK=14452351 KO=1 ) 2024-04-13 00:18:13,421 INFO > userRestLogin (OK=14452351 KO=1 ) 2024-04-13 00:18:13,421 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 00:18:13,422 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-13 00:18:13,422 INFO ), but actually found 502 2024-04-13 00:18:13,422 INFO 2024-04-13 00:18:13,422 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,422 INFO [#########################################################################-] 99% 2024-04-13 00:18:13,422 INFO waiting: 0 / active: 64 / done: 14452352 2024-04-13 00:18:13,422 INFO ================================================================================ 2024-04-13 00:18:13,422 INFO 2024-04-13 00:18:13,422 INFO 2024-04-13 00:18:13,422 INFO ================================================================================ 2024-04-13 00:18:13,422 INFO 2024-04-13 01:16:09 3630s elapsed 2024-04-13 00:18:13,422 INFO ---- Requests ------------------------------------------------------------------ 2024-04-13 00:18:13,422 INFO > Global (OK=14452415 KO=1 ) 2024-04-13 00:18:13,422 INFO > userRestLogin (OK=14452415 KO=1 ) 2024-04-13 00:18:13,422 INFO ---- Errors -------------------------------------------------------------------- 2024-04-13 00:18:13,422 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-13 00:18:13,422 INFO ), but actually found 502 2024-04-13 00:18:13,422 INFO 2024-04-13 00:18:13,422 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-13 00:18:13,422 INFO [##########################################################################]100% 2024-04-13 00:18:13,422 INFO waiting: 0 / active: 0 / done: 14452416 2024-04-13 00:18:13,422 INFO ================================================================================ 2024-04-13 00:18:13,422 INFO 2024-04-13 00:18:13,423 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-13 00:18:13,423 INFO 2024-04-13 00:18:13,423 INFO ---------------------- 2024-04-13 00:18:13,423 INFO [13/Apr/2024 01:16:09] Create tar file authn-users-4000.tar.gz 2024-04-13 00:18:13,423 INFO ---------------------- 2024-04-13 00:18:13,423 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-13 00:18:13,423 INFO RESULT_FOLDER=authn-users-4000 2024-04-13 00:18:13,423 INFO SIMULATION_PATH=authn-users-4000/loadtest-20240412231539007 2024-04-13 00:18:13,423 INFO PWD=/results/gatling/authn-users-4000/loadtest-20240412231539007 2024-04-13 00:18:13,423 INFO [cmd] tar -czvf /results/gatling//authn-users-4000.tar.gz --exclude=simulation.log * 2024-04-13 00:18:13,423 INFO /results/gatling 2024-04-13 00:18:13,423 INFO 2024-04-13 00:18:13,423 INFO ---------------------- 2024-04-13 00:18:13,423 INFO [13/Apr/2024 01:16:09] Update /results/gatling//latest 2024-04-13 00:18:13,423 INFO ---------------------- 2024-04-13 00:18:13,423 INFO 2024-04-13 00:18:13,423 INFO ---------------------- 2024-04-13 00:18:13,423 INFO [13/Apr/2024 01:16:09] Update /results/gatling//latest.tar.gz 2024-04-13 00:18:13,423 INFO ---------------------- 2024-04-13 00:18:13,423 INFO ________________________________________________________________________________ [2024-04-13 00:18:13] authn-users-4000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-13 00:18:13,424 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-4000/stderr.txt is small (0 lines), printing all lines: 2024-04-13 00:18:13,424 DEBUG ----- output ----- 2024-04-13 00:18:13,424 DEBUG 2024-04-13 00:18:13,424 INFO ________________________________________________________________________________ [2024-04-13 00:18:13] authn-users-4000 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-13 00:18:13] authn-users-4000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-13 00:18:13] authn-users-4000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped