--Task-- name: authn-users-6000 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-3000'] preceding_task: authn-users-3000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 6000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-19 23:13:34] authn-users-6000 pre : N/A ________________________________________________________________________________ [2024-04-19 23:13:34] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-19 23:13:34] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-6000/stdout.txt Pod log : /results/orders/authn-users-6000 2024-04-19 23:13:34,525 INFO 2024-04-19 23:13:34,525 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 23:13:34,525 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:34,822 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:34,822 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:34,822 DEBUG --- stdout --- 2024-04-19 23:13:34,822 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:34,822 DEBUG --- stderr --- 2024-04-19 23:13:34,822 DEBUG ________________________________________________________________________________ [2024-04-19 23:13:34] authn-users-6000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-19 23:13:34,823 INFO 2024-04-19 23:13:34,823 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 23:13:34,823 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:35,149 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:35,149 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:35,149 DEBUG --- stdout --- 2024-04-19 23:13:35,149 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:35,149 DEBUG --- stderr --- 2024-04-19 23:13:35,150 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-6000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-19 23:13:35] authn-users-6000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-19 23:13:35,150 INFO 2024-04-19 23:13:35,150 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 23:13:35,150 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:35,461 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:35,461 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:35,461 DEBUG --- stdout --- 2024-04-19 23:13:35,461 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:35,461 DEBUG --- stderr --- 2024-04-19 23:13:35,461 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/order.json { "name": "authn-users-6000", "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-6000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-6000", "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=6000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-19 23:13:35] authn-users-6000 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 23:13:35,462 INFO 2024-04-19 23:13:35,462 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-19 23:13:35,463 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 23:13:35,693 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-19 23:13:35,693 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:35,693 DEBUG --- stdout --- 2024-04-19 23:13:35,693 DEBUG WAITING 2024-04-19 23:13:35,693 DEBUG --- stderr --- 2024-04-19 23:13:35,693 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-19 23:13:35] authn-users-6000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-19 23:13:35,694 INFO 2024-04-19 23:13:35,694 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 23:13:35,694 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:35,995 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:35,995 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:35,995 DEBUG --- stdout --- 2024-04-19 23:13:35,995 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:35,995 DEBUG --- stderr --- 2024-04-19 23:13:35,995 DEBUG 2024-04-19 23:13:35,996 INFO 2024-04-19 23:13:35,996 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-6000/order.json overseer-0-5c67bf94db-77mh9:/results/orders/order.json 2024-04-19 23:13:35,996 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-19 23:13:37,363 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:37,364 DEBUG --- stdout --- 2024-04-19 23:13:37,364 DEBUG 2024-04-19 23:13:37,364 DEBUG --- stderr --- 2024-04-19 23:13:37,364 DEBUG Order has been successfully forwarded to overseer [2024-04-19 23:13:37] - INFO: Check if Gatling simulation started successfully 2024-04-19 23:13:37,364 INFO 2024-04-19 23:13:37,364 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-19 23:13:37,364 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-19 23:13:37,676 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-19 23:13:37,676 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:37,676 DEBUG --- stdout --- 2024-04-19 23:13:37,676 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-19 23:13:37,676 DEBUG --- stderr --- 2024-04-19 23:13:37,676 DEBUG 2024-04-19 23:13:37,676 INFO 2024-04-19 23:13:37,676 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-6000/stdout.txt | grep "started..." 2024-04-19 23:13:37,676 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-19 23:13:38,433 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-19 23:13:44,226 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-19 23:13:50,056 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-19 23:13:50,057 INFO [loop_until]: OK (rc = 0) 2024-04-19 23:13:50,057 DEBUG --- stdout --- 2024-04-19 23:13:50,057 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-04-19 23:13:50,057 DEBUG --- stderr --- 2024-04-19 23:13:50,057 DEBUG ________________________________________________________________________________ [2024-04-19 23:13:50] authn-users-6000 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 05s, 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-20 00:16:00] authn-users-6000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-20 00:16:00,913 INFO 2024-04-20 00:16:00,914 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-20 00:16:00,914 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:01,232 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:01,232 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:01,232 DEBUG --- stdout --- 2024-04-20 00:16:01,232 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:01,232 DEBUG --- stderr --- 2024-04-20 00:16:01,232 DEBUG 2024-04-20 00:16:01,233 INFO 2024-04-20 00:16:01,233 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-20 00:16:01,233 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 00:16:02,020 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:02,021 DEBUG --- stdout --- 2024-04-20 00:16:02,021 DEBUG 2024-04-20 00:16:02,021 DEBUG --- stderr --- 2024-04-20 00:16:02,021 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-20 00:16:02] authn-users-6000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-20 00:16:02] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/order.json 2024-04-20 00:16:02,023 INFO 2024-04-20 00:16:02,023 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-20 00:16:02,023 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:02,379 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:02,379 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:02,379 DEBUG --- stdout --- 2024-04-20 00:16:02,379 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:02,379 DEBUG --- stderr --- 2024-04-20 00:16:02,380 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 00:16:02,380 INFO 2024-04-20 00:16:02,380 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-20 00:16:02,380 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:02,685 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:02,685 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:02,685 DEBUG --- stdout --- 2024-04-20 00:16:02,685 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:02,685 DEBUG --- stderr --- 2024-04-20 00:16:02,685 DEBUG 2024-04-20 00:16:02,685 INFO 2024-04-20 00:16:02,685 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-6000/rc.txt" ]; then echo "/results/orders/authn-users-6000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/rc.txt file found" 2024-04-20 00:16:02,686 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 00:16:03,517 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 00:16:03,517 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:03,517 DEBUG --- stdout --- 2024-04-20 00:16:03,517 DEBUG /results/orders/authn-users-6000/rc.txt file found 2024-04-20 00:16:03,517 DEBUG --- stderr --- 2024-04-20 00:16:03,517 DEBUG 2024-04-20 00:16:03,517 INFO 2024-04-20 00:16:03,517 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-20 00:16:03,517 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:03,816 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:03,816 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:03,816 DEBUG --- stdout --- 2024-04-20 00:16:03,816 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:03,816 DEBUG --- stderr --- 2024-04-20 00:16:03,816 DEBUG 2024-04-20 00:16:03,816 INFO 2024-04-20 00:16:03,817 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-6000/rc.txt 2024-04-20 00:16:04,635 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 00:16:04,635 DEBUG --- stdout --- 2024-04-20 00:16:04,635 DEBUG 4 /results/orders/authn-users-6000/rc.txt 2024-04-20 00:16:04,635 DEBUG --- stderr --- 2024-04-20 00:16:04,636 DEBUG 2024-04-20 00:16:04,636 INFO - Download file (size 4k) 2024-04-20 00:16:04,636 INFO 2024-04-20 00:16:04,636 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-20 00:16:04,636 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:04,946 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:04,947 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:04,947 DEBUG --- stdout --- 2024-04-20 00:16:04,947 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:04,947 DEBUG --- stderr --- 2024-04-20 00:16:04,947 DEBUG source : /results/orders/authn-users-6000/rc.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/rc.txt 2024-04-20 00:16:04,947 INFO 2024-04-20 00:16:04,947 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-20 00:16:04,947 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:05,248 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:05,249 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:05,249 DEBUG --- stdout --- 2024-04-20 00:16:05,249 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:05,249 DEBUG --- stderr --- 2024-04-20 00:16:05,249 DEBUG 2024-04-20 00:16:05,249 INFO 2024-04-20 00:16:05,249 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-6000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/rc.txt 2024-04-20 00:16:05,249 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 00:16:05,991 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:05,991 DEBUG --- stdout --- 2024-04-20 00:16:05,991 DEBUG tar: Removing leading `/' from member names 2024-04-20 00:16:05,991 DEBUG --- stderr --- 2024-04-20 00:16:05,991 DEBUG 2024-04-20 00:16:05,991 INFO 2024-04-20 00:16:05,992 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-20 00:16:05,992 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:06,312 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:06,312 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:06,312 DEBUG --- stdout --- 2024-04-20 00:16:06,312 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:06,312 DEBUG --- stderr --- 2024-04-20 00:16:06,312 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 00:16:06,313 INFO 2024-04-20 00:16:06,313 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-20 00:16:06,313 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:06,639 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:06,639 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:06,639 DEBUG --- stdout --- 2024-04-20 00:16:06,639 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:06,640 DEBUG --- stderr --- 2024-04-20 00:16:06,640 DEBUG 2024-04-20 00:16:06,640 INFO 2024-04-20 00:16:06,640 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-6000/stderr.txt" ]; then echo "/results/orders/authn-users-6000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/stderr.txt file found" 2024-04-20 00:16:06,640 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 00:16:07,479 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 00:16:07,479 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:07,480 DEBUG --- stdout --- 2024-04-20 00:16:07,480 DEBUG /results/orders/authn-users-6000/stderr.txt file found 2024-04-20 00:16:07,481 DEBUG --- stderr --- 2024-04-20 00:16:07,481 DEBUG 2024-04-20 00:16:07,481 INFO 2024-04-20 00:16:07,481 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-20 00:16:07,481 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:07,826 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:07,827 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:07,827 DEBUG --- stdout --- 2024-04-20 00:16:07,827 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:07,827 DEBUG --- stderr --- 2024-04-20 00:16:07,827 DEBUG 2024-04-20 00:16:07,827 INFO 2024-04-20 00:16:07,827 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-6000/stderr.txt 2024-04-20 00:16:08,700 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 00:16:08,700 DEBUG --- stdout --- 2024-04-20 00:16:08,700 DEBUG 0 /results/orders/authn-users-6000/stderr.txt 2024-04-20 00:16:08,700 DEBUG --- stderr --- 2024-04-20 00:16:08,700 DEBUG 2024-04-20 00:16:08,700 INFO - Download file (size 0k) 2024-04-20 00:16:08,700 INFO 2024-04-20 00:16:08,700 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-20 00:16:08,700 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:09,004 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:09,004 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:09,004 DEBUG --- stdout --- 2024-04-20 00:16:09,004 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:09,004 DEBUG --- stderr --- 2024-04-20 00:16:09,004 DEBUG source : /results/orders/authn-users-6000/stderr.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stderr.txt 2024-04-20 00:16:09,005 INFO 2024-04-20 00:16:09,005 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-20 00:16:09,005 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:09,309 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:09,309 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:09,309 DEBUG --- stdout --- 2024-04-20 00:16:09,309 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:09,309 DEBUG --- stderr --- 2024-04-20 00:16:09,309 DEBUG 2024-04-20 00:16:09,310 INFO 2024-04-20 00:16:09,310 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-6000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stderr.txt 2024-04-20 00:16:09,310 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 00:16:10,079 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:10,079 DEBUG --- stdout --- 2024-04-20 00:16:10,079 DEBUG tar: Removing leading `/' from member names 2024-04-20 00:16:10,079 DEBUG --- stderr --- 2024-04-20 00:16:10,079 DEBUG 2024-04-20 00:16:10,079 INFO 2024-04-20 00:16:10,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-20 00:16:10,079 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:10,378 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:10,378 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:10,378 DEBUG --- stdout --- 2024-04-20 00:16:10,378 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:10,378 DEBUG --- stderr --- 2024-04-20 00:16:10,378 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 00:16:10,378 INFO 2024-04-20 00:16:10,378 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-20 00:16:10,378 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:10,683 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:10,683 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:10,683 DEBUG --- stdout --- 2024-04-20 00:16:10,683 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:10,683 DEBUG --- stderr --- 2024-04-20 00:16:10,683 DEBUG 2024-04-20 00:16:10,684 INFO 2024-04-20 00:16:10,684 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-6000/stdout.txt" ]; then echo "/results/orders/authn-users-6000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/stdout.txt file found" 2024-04-20 00:16:10,684 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 00:16:11,501 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 00:16:11,501 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:11,502 DEBUG --- stdout --- 2024-04-20 00:16:11,502 DEBUG /results/orders/authn-users-6000/stdout.txt file found 2024-04-20 00:16:11,502 DEBUG --- stderr --- 2024-04-20 00:16:11,502 DEBUG 2024-04-20 00:16:11,502 INFO 2024-04-20 00:16:11,502 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-20 00:16:11,502 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:11,821 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:11,821 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:11,821 DEBUG --- stdout --- 2024-04-20 00:16:11,821 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:11,821 DEBUG --- stderr --- 2024-04-20 00:16:11,821 DEBUG 2024-04-20 00:16:11,821 INFO 2024-04-20 00:16:11,821 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-6000/stdout.txt 2024-04-20 00:16:12,638 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 00:16:12,640 DEBUG --- stdout --- 2024-04-20 00:16:12,640 DEBUG 508 /results/orders/authn-users-6000/stdout.txt 2024-04-20 00:16:12,640 DEBUG --- stderr --- 2024-04-20 00:16:12,640 DEBUG 2024-04-20 00:16:12,640 INFO - Download file (size 508k) 2024-04-20 00:16:12,641 INFO 2024-04-20 00:16:12,641 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-20 00:16:12,641 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:12,970 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:12,970 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:12,970 DEBUG --- stdout --- 2024-04-20 00:16:12,970 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:12,970 DEBUG --- stderr --- 2024-04-20 00:16:12,970 DEBUG source : /results/orders/authn-users-6000/stdout.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stdout.txt 2024-04-20 00:16:12,971 INFO 2024-04-20 00:16:12,971 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-20 00:16:12,971 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:13,299 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:13,299 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:13,299 DEBUG --- stdout --- 2024-04-20 00:16:13,299 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:13,299 DEBUG --- stderr --- 2024-04-20 00:16:13,299 DEBUG 2024-04-20 00:16:13,300 INFO 2024-04-20 00:16:13,300 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-6000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stdout.txt 2024-04-20 00:16:13,300 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 00:16:14,511 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:14,511 DEBUG --- stdout --- 2024-04-20 00:16:14,511 DEBUG tar: Removing leading `/' from member names 2024-04-20 00:16:14,511 DEBUG --- stderr --- 2024-04-20 00:16:14,511 DEBUG ________________________________________________________________________________ [2024-04-20 00:16:14] authn-users-6000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-20 00:16:14,512 INFO 2024-04-20 00:16:14,512 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-20 00:16:14,512 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:14,828 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:14,828 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:14,828 DEBUG --- stdout --- 2024-04-20 00:16:14,829 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:14,829 DEBUG --- stderr --- 2024-04-20 00:16:14,829 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 00:16:14,829 INFO 2024-04-20 00:16:14,829 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-20 00:16:14,829 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:15,146 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:15,147 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:15,147 DEBUG --- stdout --- 2024-04-20 00:16:15,147 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:15,147 DEBUG --- stderr --- 2024-04-20 00:16:15,147 DEBUG 2024-04-20 00:16:15,148 INFO 2024-04-20 00:16:15,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-6000/cmd.txt" ]; then echo "/results/orders/authn-users-6000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/cmd.txt file found" 2024-04-20 00:16:15,148 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 00:16:15,968 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 00:16:15,969 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:15,969 DEBUG --- stdout --- 2024-04-20 00:16:15,969 DEBUG /results/orders/authn-users-6000/cmd.txt file found 2024-04-20 00:16:15,969 DEBUG --- stderr --- 2024-04-20 00:16:15,969 DEBUG 2024-04-20 00:16:15,969 INFO 2024-04-20 00:16:15,969 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-20 00:16:15,969 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:16,277 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:16,278 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:16,278 DEBUG --- stdout --- 2024-04-20 00:16:16,278 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:16,278 DEBUG --- stderr --- 2024-04-20 00:16:16,278 DEBUG 2024-04-20 00:16:16,278 INFO 2024-04-20 00:16:16,278 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-6000/cmd.txt 2024-04-20 00:16:17,101 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 00:16:17,101 DEBUG --- stdout --- 2024-04-20 00:16:17,101 DEBUG 4 /results/orders/authn-users-6000/cmd.txt 2024-04-20 00:16:17,101 DEBUG --- stderr --- 2024-04-20 00:16:17,101 DEBUG 2024-04-20 00:16:17,101 INFO - Download file (size 4k) 2024-04-20 00:16:17,102 INFO 2024-04-20 00:16:17,102 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-20 00:16:17,102 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:17,466 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:17,467 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:17,467 DEBUG --- stdout --- 2024-04-20 00:16:17,467 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:17,467 DEBUG --- stderr --- 2024-04-20 00:16:17,467 DEBUG source : /results/orders/authn-users-6000/cmd.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/cmd.txt 2024-04-20 00:16:17,467 INFO 2024-04-20 00:16:17,467 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-20 00:16:17,467 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 00:16:17,798 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 00:16:17,799 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:17,799 DEBUG --- stdout --- 2024-04-20 00:16:17,799 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 00:16:17,800 DEBUG --- stderr --- 2024-04-20 00:16:17,800 DEBUG 2024-04-20 00:16:17,800 INFO 2024-04-20 00:16:17,800 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-6000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/cmd.txt 2024-04-20 00:16:17,800 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 00:16:18,539 INFO [loop_until]: OK (rc = 0) 2024-04-20 00:16:18,539 DEBUG --- stdout --- 2024-04-20 00:16:18,539 DEBUG tar: Removing leading `/' from member names 2024-04-20 00:16:18,539 DEBUG --- stderr --- 2024-04-20 00:16:18,539 DEBUG 2024-04-20 00:16:18,539 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/cmd.txt is small (1 lines), printing all lines: 2024-04-20 00:16:18,539 DEBUG ----- output ----- 2024-04-20 00:16:18,540 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-6000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-20 00:16:18,540 INFO ________________________________________________________________________________ [2024-04-20 00:16:18] authn-users-6000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-20 00:16:18,540 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/rc.txt is small (1 lines), printing all lines: 2024-04-20 00:16:18,540 DEBUG ----- output ----- 2024-04-20 00:16:18,540 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-20 00:16:18,540 INFO ________________________________________________________________________________ [2024-04-20 00:16:18] authn-users-6000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-20 00:16:18,541 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-6000/stdout.txt is 8746 lines): 2024-04-20 00:16:18,542 DEBUG ----- output ----- 2024-04-20 00:16:18,542 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-20 00:16:18,542 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-20 00:16:18,542 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-20 00:16:18,542 INFO 2024-04-20 00:16:18,542 INFO ================================================================================ 2024-04-20 00:16:18,542 INFO 2024-04-20 00:13:50 5s elapsed 2024-04-20 00:16:18,542 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,542 INFO > Global (OK=2498 KO=0 ) 2024-04-20 00:16:18,542 INFO > userRestLogin (OK=2498 KO=0 ) 2024-04-20 00:16:18,542 INFO 2024-04-20 00:16:18,542 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,542 INFO [- ] 0% 2024-04-20 00:16:18,542 INFO waiting: 21683967 / active: 9 / done: 2498 2024-04-20 00:16:18,542 INFO ================================================================================ 2024-04-20 00:16:18,542 INFO 2024-04-20 00:16:18,542 INFO 2024-04-20 00:16:18,542 INFO ================================================================================ 2024-04-20 00:16:18,542 INFO 2024-04-20 00:13:55 10s elapsed 2024-04-20 00:16:18,542 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,542 INFO > Global (OK=9991 KO=0 ) 2024-04-20 00:16:18,542 INFO > userRestLogin (OK=9991 KO=0 ) 2024-04-20 00:16:18,542 INFO 2024-04-20 00:16:18,542 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,542 INFO [- ] 0% 2024-04-20 00:16:18,542 INFO waiting: 21676465 / active: 19 / done: 9990 2024-04-20 00:16:18,542 INFO ================================================================================ 2024-04-20 00:16:18,542 INFO 2024-04-20 00:16:18,542 INFO 2024-04-20 00:16:18,542 INFO ================================================================================ 2024-04-20 00:16:18,542 INFO 2024-04-20 00:14:00 15s elapsed 2024-04-20 00:16:18,542 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,542 INFO > Global (OK=22492 KO=0 ) 2024-04-20 00:16:18,542 INFO > userRestLogin (OK=22492 KO=0 ) 2024-04-20 00:16:18,542 INFO 2024-04-20 00:16:18,542 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,542 INFO [- ] 0% 2024-04-20 00:16:18,543 INFO waiting: 21663955 / active: 27 / done: 22492 2024-04-20 00:16:18,543 INFO ================================================================================ 2024-04-20 00:16:18,543 INFO 2024-04-20 00:16:18,543 INFO 2024-04-20 00:16:18,543 INFO ================================================================================ 2024-04-20 00:16:18,543 INFO 2024-04-20 00:14:05 20s elapsed 2024-04-20 00:16:18,543 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,543 INFO > Global (OK=39983 KO=0 ) 2024-04-20 00:16:18,543 INFO > userRestLogin (OK=39983 KO=0 ) 2024-04-20 00:16:18,543 INFO 2024-04-20 00:16:18,543 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,543 INFO [- ] 0% 2024-04-20 00:16:18,543 INFO waiting: 21646449 / active: 43 / done: 39982 2024-04-20 00:16:18,543 INFO ================================================================================ 2024-04-20 00:16:18,543 INFO 2024-04-20 00:16:18,543 INFO 2024-04-20 00:16:18,543 INFO ================================================================================ 2024-04-20 00:16:18,543 INFO 2024-04-20 00:14:10 25s elapsed 2024-04-20 00:16:18,543 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,543 INFO > Global (OK=62469 KO=0 ) 2024-04-20 00:16:18,543 INFO > userRestLogin (OK=62469 KO=0 ) 2024-04-20 00:16:18,543 INFO 2024-04-20 00:16:18,543 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,543 INFO [- ] 0% 2024-04-20 00:16:18,543 INFO waiting: 21623943 / active: 62 / done: 62469 2024-04-20 00:16:18,543 INFO ================================================================================ 2024-04-20 00:16:18,543 INFO 2024-04-20 00:16:18,543 INFO 2024-04-20 00:16:18,543 INFO ================================================================================ 2024-04-20 00:16:18,543 INFO 2024-04-20 00:14:15 30s elapsed 2024-04-20 00:16:18,543 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,543 INFO > Global (OK=89814 KO=0 ) 2024-04-20 00:16:18,543 INFO > userRestLogin (OK=89814 KO=0 ) 2024-04-20 00:16:18,543 INFO 2024-04-20 00:16:18,543 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,543 INFO [- ] 0% 2024-04-20 00:16:18,543 INFO waiting: 21596581 / active: 79 / done: 89814 2024-04-20 00:16:18,543 INFO ================================================================================ 2024-04-20 00:16:18,543 INFO 2024-04-20 00:16:18,543 INFO 2024-04-20 00:16:18,543 INFO ================================================================================ 2024-04-20 00:16:18,543 INFO 2024-04-20 00:14:20 35s elapsed 2024-04-20 00:16:18,544 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,544 INFO > Global (OK=119964 KO=0 ) 2024-04-20 00:16:18,544 INFO > userRestLogin (OK=119964 KO=0 ) 2024-04-20 00:16:18,544 INFO 2024-04-20 00:16:18,544 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,544 INFO [- ] 0% 2024-04-20 00:16:18,544 INFO waiting: 21566428 / active: 83 / done: 119963 2024-04-20 00:16:18,544 INFO ================================================================================ 2024-04-20 00:16:18,544 INFO 2024-04-20 00:16:18,544 INFO 2024-04-20 00:16:18,544 INFO ================================================================================ 2024-04-20 00:16:18,544 INFO 2024-04-20 00:14:25 40s elapsed 2024-04-20 00:16:18,544 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,544 INFO > Global (OK=150224 KO=0 ) 2024-04-20 00:16:18,544 INFO > userRestLogin (OK=150224 KO=0 ) 2024-04-20 00:16:18,544 INFO 2024-04-20 00:16:18,544 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,544 INFO [- ] 0% 2024-04-20 00:16:18,544 INFO waiting: 21536142 / active: 109 / done: 150223 2024-04-20 00:16:18,544 INFO ================================================================================ 2024-04-20 00:16:18,544 INFO 2024-04-20 00:16:18,544 INFO 2024-04-20 00:16:18,544 INFO [...] 2024-04-20 00:16:18,546 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,547 INFO > Global (OK=21536315 KO=0 ) 2024-04-20 00:16:18,547 INFO > userRestLogin (OK=21536315 KO=0 ) 2024-04-20 00:16:18,547 INFO 2024-04-20 00:16:18,547 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,547 INFO [#########################################################################-] 99% 2024-04-20 00:16:18,547 INFO waiting: 150062 / active: 97 / done: 21536315 2024-04-20 00:16:18,547 INFO ================================================================================ 2024-04-20 00:16:18,547 INFO 2024-04-20 00:16:18,547 INFO 2024-04-20 00:16:18,547 INFO ================================================================================ 2024-04-20 00:16:18,547 INFO 2024-04-20 01:13:55 3610s elapsed 2024-04-20 00:16:18,547 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,547 INFO > Global (OK=21566364 KO=0 ) 2024-04-20 00:16:18,547 INFO > userRestLogin (OK=21566364 KO=0 ) 2024-04-20 00:16:18,547 INFO 2024-04-20 00:16:18,547 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,547 INFO [#########################################################################-] 99% 2024-04-20 00:16:18,547 INFO waiting: 120030 / active: 82 / done: 21566362 2024-04-20 00:16:18,547 INFO ================================================================================ 2024-04-20 00:16:18,547 INFO 2024-04-20 00:16:18,547 INFO 2024-04-20 00:16:18,547 INFO ================================================================================ 2024-04-20 00:16:18,547 INFO 2024-04-20 01:14:00 3615s elapsed 2024-04-20 00:16:18,547 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,547 INFO > Global (OK=21596350 KO=0 ) 2024-04-20 00:16:18,547 INFO > userRestLogin (OK=21596350 KO=0 ) 2024-04-20 00:16:18,547 INFO 2024-04-20 00:16:18,547 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,547 INFO [#########################################################################-] 99% 2024-04-20 00:16:18,547 INFO waiting: 89993 / active: 132 / done: 21596349 2024-04-20 00:16:18,547 INFO ================================================================================ 2024-04-20 00:16:18,547 INFO 2024-04-20 00:16:18,547 INFO 2024-04-20 00:16:18,547 INFO ================================================================================ 2024-04-20 00:16:18,547 INFO 2024-04-20 01:14:05 3620s elapsed 2024-04-20 00:16:18,547 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,547 INFO > Global (OK=21626335 KO=0 ) 2024-04-20 00:16:18,547 INFO > userRestLogin (OK=21626335 KO=0 ) 2024-04-20 00:16:18,547 INFO 2024-04-20 00:16:18,548 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,548 INFO [#########################################################################-] 99% 2024-04-20 00:16:18,548 INFO waiting: 60043 / active: 97 / done: 21626334 2024-04-20 00:16:18,548 INFO ================================================================================ 2024-04-20 00:16:18,548 INFO 2024-04-20 00:16:18,548 INFO 2024-04-20 00:16:18,548 INFO ================================================================================ 2024-04-20 00:16:18,548 INFO 2024-04-20 01:14:10 3625s elapsed 2024-04-20 00:16:18,548 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,548 INFO > Global (OK=21656267 KO=0 ) 2024-04-20 00:16:18,548 INFO > userRestLogin (OK=21656267 KO=0 ) 2024-04-20 00:16:18,548 INFO 2024-04-20 00:16:18,548 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,548 INFO [#########################################################################-] 99% 2024-04-20 00:16:18,548 INFO waiting: 30115 / active: 94 / done: 21656265 2024-04-20 00:16:18,548 INFO ================================================================================ 2024-04-20 00:16:18,548 INFO 2024-04-20 00:16:18,548 INFO 2024-04-20 00:16:18,548 INFO ================================================================================ 2024-04-20 00:16:18,548 INFO 2024-04-20 01:14:15 3630s elapsed 2024-04-20 00:16:18,548 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,548 INFO > Global (OK=21686376 KO=0 ) 2024-04-20 00:16:18,548 INFO > userRestLogin (OK=21686376 KO=0 ) 2024-04-20 00:16:18,548 INFO 2024-04-20 00:16:18,548 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,548 INFO [#########################################################################-] 99% 2024-04-20 00:16:18,548 INFO waiting: 0 / active: 99 / done: 21686375 2024-04-20 00:16:18,548 INFO ================================================================================ 2024-04-20 00:16:18,548 INFO 2024-04-20 00:16:18,548 INFO 2024-04-20 00:16:18,548 INFO ================================================================================ 2024-04-20 00:16:18,548 INFO 2024-04-20 01:14:15 3630s elapsed 2024-04-20 00:16:18,548 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 00:16:18,548 INFO > Global (OK=21686474 KO=0 ) 2024-04-20 00:16:18,548 INFO > userRestLogin (OK=21686474 KO=0 ) 2024-04-20 00:16:18,548 INFO 2024-04-20 00:16:18,548 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 00:16:18,548 INFO [##########################################################################]100% 2024-04-20 00:16:18,548 INFO waiting: 0 / active: 0 / done: 21686474 2024-04-20 00:16:18,548 INFO ================================================================================ 2024-04-20 00:16:18,548 INFO 2024-04-20 00:16:18,548 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-20 00:16:18,549 INFO 2024-04-20 00:16:18,549 INFO ---------------------- 2024-04-20 00:16:18,549 INFO [20/Apr/2024 01:14:15] Create tar file authn-users-6000.tar.gz 2024-04-20 00:16:18,549 INFO ---------------------- 2024-04-20 00:16:18,549 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-20 00:16:18,549 INFO RESULT_FOLDER=authn-users-6000 2024-04-20 00:16:18,549 INFO SIMULATION_PATH=authn-users-6000/loadtest-20240419231344870 2024-04-20 00:16:18,549 INFO PWD=/results/gatling/authn-users-6000/loadtest-20240419231344870 2024-04-20 00:16:18,549 INFO [cmd] tar -czvf /results/gatling//authn-users-6000.tar.gz --exclude=simulation.log * 2024-04-20 00:16:18,549 INFO /results/gatling 2024-04-20 00:16:18,549 INFO 2024-04-20 00:16:18,549 INFO ---------------------- 2024-04-20 00:16:18,549 INFO [20/Apr/2024 01:14:15] Update /results/gatling//latest 2024-04-20 00:16:18,549 INFO ---------------------- 2024-04-20 00:16:18,549 INFO 2024-04-20 00:16:18,549 INFO ---------------------- 2024-04-20 00:16:18,549 INFO [20/Apr/2024 01:14:15] Update /results/gatling//latest.tar.gz 2024-04-20 00:16:18,549 INFO ---------------------- 2024-04-20 00:16:18,549 INFO ________________________________________________________________________________ [2024-04-20 00:16:18] authn-users-6000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-20 00:16:18,549 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/03_authn-users-6000/stderr.txt is small (0 lines), printing all lines: 2024-04-20 00:16:18,549 DEBUG ----- output ----- 2024-04-20 00:16:18,549 DEBUG 2024-04-20 00:16:18,549 INFO ________________________________________________________________________________ [2024-04-20 00:16:18] authn-users-6000 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-20 00:16:18] authn-users-6000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-20 00:16:18] authn-users-6000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped