--Task-- name: authn-users-9500 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-9000'] preceding_task: authn-users-9000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 9500, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-22 02:24:33] authn-users-9500 pre : N/A ________________________________________________________________________________ [2024-04-22 02:24:33] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-22 02:24:33] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-9500/stdout.txt Pod log : /results/orders/authn-users-9500 2024-04-22 02:24:33,717 INFO 2024-04-22 02:24:33,717 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-22 02:24:33,717 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:34,016 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:34,016 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:34,016 DEBUG --- stdout --- 2024-04-22 02:24:34,016 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:34,016 DEBUG --- stderr --- 2024-04-22 02:24:34,016 DEBUG ________________________________________________________________________________ [2024-04-22 02:24:34] authn-users-9500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-22 02:24:34,017 INFO 2024-04-22 02:24:34,017 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-22 02:24:34,017 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:34,321 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:34,321 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:34,321 DEBUG --- stdout --- 2024-04-22 02:24:34,321 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:34,321 DEBUG --- stderr --- 2024-04-22 02:24:34,321 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-9500 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-22 02:24:34] authn-users-9500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-22 02:24:34,322 INFO 2024-04-22 02:24:34,322 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-22 02:24:34,322 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:34,652 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:34,652 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:34,652 DEBUG --- stdout --- 2024-04-22 02:24:34,652 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:34,652 DEBUG --- stderr --- 2024-04-22 02:24:34,653 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/order.json { "name": "authn-users-9500", "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-9500 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/authn-users-9500", "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=9500 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-22 02:24:34] authn-users-9500 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-22 02:24:34,654 INFO 2024-04-22 02:24:34,654 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-22 02:24:34,654 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-22 02:24:34,888 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 02:24:34,889 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:34,889 DEBUG --- stdout --- 2024-04-22 02:24:34,889 DEBUG WAITING 2024-04-22 02:24:34,889 DEBUG --- stderr --- 2024-04-22 02:24:34,889 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-22 02:24:34] authn-users-9500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-22 02:24:34,889 INFO 2024-04-22 02:24:34,889 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-22 02:24:34,890 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:35,207 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:35,207 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:35,208 DEBUG --- stdout --- 2024-04-22 02:24:35,208 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:35,208 DEBUG --- stderr --- 2024-04-22 02:24:35,208 DEBUG 2024-04-22 02:24:35,208 INFO 2024-04-22 02:24:35,208 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/05_authn-users-9500/order.json overseer-0-68c5d5b884-p97w5:/results/orders/order.json 2024-04-22 02:24:35,208 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 02:24:36,594 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:36,594 DEBUG --- stdout --- 2024-04-22 02:24:36,594 DEBUG 2024-04-22 02:24:36,594 DEBUG --- stderr --- 2024-04-22 02:24:36,594 DEBUG Order has been successfully forwarded to overseer [2024-04-22 02:24:36] - INFO: Check if Gatling simulation started successfully 2024-04-22 02:24:36,595 INFO 2024-04-22 02:24:36,595 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-22 02:24:36,595 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 02:24:36,900 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 02:24:36,901 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:36,901 DEBUG --- stdout --- 2024-04-22 02:24:36,901 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 02:24:36,901 DEBUG --- stderr --- 2024-04-22 02:24:36,901 DEBUG 2024-04-22 02:24:36,901 INFO 2024-04-22 02:24:36,901 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 -- head -n 25 /results/orders/authn-users-9500/stdout.txt | grep "started..." 2024-04-22 02:24:36,901 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-22 02:24:37,652 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-22 02:24:43,414 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-22 02:24:49,176 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-22 02:24:49,176 INFO [loop_until]: OK (rc = 0) 2024-04-22 02:24:49,176 DEBUG --- stdout --- 2024-04-22 02:24:49,176 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-22 02:24:49,176 DEBUG --- stderr --- 2024-04-22 02:24:49,176 DEBUG ________________________________________________________________________________ [2024-04-22 02:24:49] authn-users-9500 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 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-22 03:26:59] authn-users-9500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-22 03:26:59,743 INFO 2024-04-22 03:26:59,743 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-22 03:26:59,743 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:00,148 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:00,148 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:00,148 DEBUG --- stdout --- 2024-04-22 03:27:00,148 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:00,149 DEBUG --- stderr --- 2024-04-22 03:27:00,149 DEBUG 2024-04-22 03:27:00,149 INFO 2024-04-22 03:27:00,149 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 -- rm -f /results/orders/order.json 2024-04-22 03:27:00,149 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 03:27:00,950 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:00,950 DEBUG --- stdout --- 2024-04-22 03:27:00,951 DEBUG 2024-04-22 03:27:00,951 DEBUG --- stderr --- 2024-04-22 03:27:00,951 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-22 03:27:00] authn-users-9500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-22 03:27:00] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/order.json 2024-04-22 03:27:00,951 INFO 2024-04-22 03:27:00,952 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-22 03:27:00,952 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:01,265 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:01,265 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:01,265 DEBUG --- stdout --- 2024-04-22 03:27:01,265 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:01,265 DEBUG --- stderr --- 2024-04-22 03:27:01,266 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 03:27:01,266 INFO 2024-04-22 03:27:01,266 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-22 03:27:01,266 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:01,585 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:01,586 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:01,586 DEBUG --- stdout --- 2024-04-22 03:27:01,586 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:01,586 DEBUG --- stderr --- 2024-04-22 03:27:01,586 DEBUG 2024-04-22 03:27:01,587 INFO 2024-04-22 03:27:01,587 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-9500/rc.txt" ]; then echo "/results/orders/authn-users-9500/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-9500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-9500/rc.txt file found" 2024-04-22 03:27:01,587 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 03:27:02,423 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 03:27:02,424 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:02,424 DEBUG --- stdout --- 2024-04-22 03:27:02,424 DEBUG /results/orders/authn-users-9500/rc.txt file found 2024-04-22 03:27:02,424 DEBUG --- stderr --- 2024-04-22 03:27:02,424 DEBUG 2024-04-22 03:27:02,424 INFO 2024-04-22 03:27:02,424 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-22 03:27:02,424 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:02,739 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:02,739 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:02,739 DEBUG --- stdout --- 2024-04-22 03:27:02,739 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:02,739 DEBUG --- stderr --- 2024-04-22 03:27:02,739 DEBUG 2024-04-22 03:27:02,739 INFO 2024-04-22 03:27:02,740 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-9500/rc.txt 2024-04-22 03:27:03,601 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 03:27:03,602 DEBUG --- stdout --- 2024-04-22 03:27:03,602 DEBUG 4 /results/orders/authn-users-9500/rc.txt 2024-04-22 03:27:03,602 DEBUG --- stderr --- 2024-04-22 03:27:03,602 DEBUG 2024-04-22 03:27:03,602 INFO - Download file (size 4k) 2024-04-22 03:27:03,602 INFO 2024-04-22 03:27:03,602 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 03:27:03,602 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:03,931 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:03,931 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:03,931 DEBUG --- stdout --- 2024-04-22 03:27:03,931 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:03,931 DEBUG --- stderr --- 2024-04-22 03:27:03,931 DEBUG source : /results/orders/authn-users-9500/rc.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/rc.txt 2024-04-22 03:27:03,932 INFO 2024-04-22 03:27:03,932 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-22 03:27:03,932 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:04,258 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:04,259 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:04,259 DEBUG --- stdout --- 2024-04-22 03:27:04,259 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:04,259 DEBUG --- stderr --- 2024-04-22 03:27:04,259 DEBUG 2024-04-22 03:27:04,260 INFO 2024-04-22 03:27:04,260 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-9500/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/rc.txt 2024-04-22 03:27:04,260 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 03:27:05,078 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:05,078 DEBUG --- stdout --- 2024-04-22 03:27:05,078 DEBUG tar: Removing leading `/' from member names 2024-04-22 03:27:05,078 DEBUG --- stderr --- 2024-04-22 03:27:05,078 DEBUG 2024-04-22 03:27:05,079 INFO 2024-04-22 03:27:05,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-22 03:27:05,079 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:05,420 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:05,420 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:05,420 DEBUG --- stdout --- 2024-04-22 03:27:05,420 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:05,420 DEBUG --- stderr --- 2024-04-22 03:27:05,420 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 03:27:05,420 INFO 2024-04-22 03:27:05,420 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-22 03:27:05,420 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:05,746 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:05,746 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:05,747 DEBUG --- stdout --- 2024-04-22 03:27:05,747 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:05,747 DEBUG --- stderr --- 2024-04-22 03:27:05,747 DEBUG 2024-04-22 03:27:05,747 INFO 2024-04-22 03:27:05,747 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-9500/stderr.txt" ]; then echo "/results/orders/authn-users-9500/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-9500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-9500/stderr.txt file found" 2024-04-22 03:27:05,747 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 03:27:06,580 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 03:27:06,581 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:06,581 DEBUG --- stdout --- 2024-04-22 03:27:06,581 DEBUG /results/orders/authn-users-9500/stderr.txt file found 2024-04-22 03:27:06,581 DEBUG --- stderr --- 2024-04-22 03:27:06,581 DEBUG 2024-04-22 03:27:06,581 INFO 2024-04-22 03:27:06,581 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-22 03:27:06,581 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:06,896 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:06,896 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:06,896 DEBUG --- stdout --- 2024-04-22 03:27:06,896 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:06,896 DEBUG --- stderr --- 2024-04-22 03:27:06,896 DEBUG 2024-04-22 03:27:06,896 INFO 2024-04-22 03:27:06,896 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-9500/stderr.txt 2024-04-22 03:27:07,731 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 03:27:07,731 DEBUG --- stdout --- 2024-04-22 03:27:07,732 DEBUG 0 /results/orders/authn-users-9500/stderr.txt 2024-04-22 03:27:07,732 DEBUG --- stderr --- 2024-04-22 03:27:07,732 DEBUG 2024-04-22 03:27:07,732 INFO - Download file (size 0k) 2024-04-22 03:27:07,732 INFO 2024-04-22 03:27:07,732 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-22 03:27:07,732 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:08,045 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:08,045 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:08,045 DEBUG --- stdout --- 2024-04-22 03:27:08,045 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:08,045 DEBUG --- stderr --- 2024-04-22 03:27:08,045 DEBUG source : /results/orders/authn-users-9500/stderr.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/stderr.txt 2024-04-22 03:27:08,046 INFO 2024-04-22 03:27:08,046 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-22 03:27:08,046 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:08,363 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:08,363 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:08,363 DEBUG --- stdout --- 2024-04-22 03:27:08,363 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:08,363 DEBUG --- stderr --- 2024-04-22 03:27:08,363 DEBUG 2024-04-22 03:27:08,364 INFO 2024-04-22 03:27:08,364 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-9500/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/stderr.txt 2024-04-22 03:27:08,364 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 03:27:09,159 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:09,159 DEBUG --- stdout --- 2024-04-22 03:27:09,159 DEBUG tar: Removing leading `/' from member names 2024-04-22 03:27:09,159 DEBUG --- stderr --- 2024-04-22 03:27:09,159 DEBUG 2024-04-22 03:27:09,160 INFO 2024-04-22 03:27:09,160 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-22 03:27:09,160 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:09,476 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:09,477 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:09,477 DEBUG --- stdout --- 2024-04-22 03:27:09,477 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:09,477 DEBUG --- stderr --- 2024-04-22 03:27:09,477 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 03:27:09,477 INFO 2024-04-22 03:27:09,477 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-22 03:27:09,477 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:09,801 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:09,802 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:09,802 DEBUG --- stdout --- 2024-04-22 03:27:09,802 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:09,802 DEBUG --- stderr --- 2024-04-22 03:27:09,802 DEBUG 2024-04-22 03:27:09,803 INFO 2024-04-22 03:27:09,803 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-9500/stdout.txt" ]; then echo "/results/orders/authn-users-9500/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-9500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-9500/stdout.txt file found" 2024-04-22 03:27:09,803 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 03:27:10,656 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 03:27:10,657 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:10,657 DEBUG --- stdout --- 2024-04-22 03:27:10,657 DEBUG /results/orders/authn-users-9500/stdout.txt file found 2024-04-22 03:27:10,657 DEBUG --- stderr --- 2024-04-22 03:27:10,657 DEBUG 2024-04-22 03:27:10,657 INFO 2024-04-22 03:27:10,657 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-22 03:27:10,657 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:11,045 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:11,045 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:11,046 DEBUG --- stdout --- 2024-04-22 03:27:11,046 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:11,046 DEBUG --- stderr --- 2024-04-22 03:27:11,046 DEBUG 2024-04-22 03:27:11,046 INFO 2024-04-22 03:27:11,046 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-9500/stdout.txt 2024-04-22 03:27:11,951 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 03:27:11,952 DEBUG --- stdout --- 2024-04-22 03:27:11,952 DEBUG 508 /results/orders/authn-users-9500/stdout.txt 2024-04-22 03:27:11,952 DEBUG --- stderr --- 2024-04-22 03:27:11,952 DEBUG 2024-04-22 03:27:11,952 INFO - Download file (size 508k) 2024-04-22 03:27:11,952 INFO 2024-04-22 03:27:11,952 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-22 03:27:11,952 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:12,291 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:12,291 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:12,291 DEBUG --- stdout --- 2024-04-22 03:27:12,291 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:12,291 DEBUG --- stderr --- 2024-04-22 03:27:12,291 DEBUG source : /results/orders/authn-users-9500/stdout.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/stdout.txt 2024-04-22 03:27:12,291 INFO 2024-04-22 03:27:12,291 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-22 03:27:12,291 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:12,612 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:12,613 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:12,613 DEBUG --- stdout --- 2024-04-22 03:27:12,613 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:12,613 DEBUG --- stderr --- 2024-04-22 03:27:12,613 DEBUG 2024-04-22 03:27:12,613 INFO 2024-04-22 03:27:12,613 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-9500/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/stdout.txt 2024-04-22 03:27:12,613 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 03:27:13,705 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:13,705 DEBUG --- stdout --- 2024-04-22 03:27:13,705 DEBUG tar: Removing leading `/' from member names 2024-04-22 03:27:13,706 DEBUG --- stderr --- 2024-04-22 03:27:13,706 DEBUG ________________________________________________________________________________ [2024-04-22 03:27:13] authn-users-9500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-22 03:27:13,706 INFO 2024-04-22 03:27:13,706 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-22 03:27:13,706 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:14,024 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:14,024 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:14,024 DEBUG --- stdout --- 2024-04-22 03:27:14,024 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:14,024 DEBUG --- stderr --- 2024-04-22 03:27:14,025 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 03:27:14,025 INFO 2024-04-22 03:27:14,025 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-22 03:27:14,025 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:14,359 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:14,360 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:14,360 DEBUG --- stdout --- 2024-04-22 03:27:14,360 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:14,360 DEBUG --- stderr --- 2024-04-22 03:27:14,360 DEBUG 2024-04-22 03:27:14,360 INFO 2024-04-22 03:27:14,360 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-9500/cmd.txt" ]; then echo "/results/orders/authn-users-9500/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-9500/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-9500/cmd.txt file found" 2024-04-22 03:27:14,360 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 03:27:15,186 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 03:27:15,187 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:15,187 DEBUG --- stdout --- 2024-04-22 03:27:15,187 DEBUG /results/orders/authn-users-9500/cmd.txt file found 2024-04-22 03:27:15,187 DEBUG --- stderr --- 2024-04-22 03:27:15,187 DEBUG 2024-04-22 03:27:15,187 INFO 2024-04-22 03:27:15,187 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-22 03:27:15,187 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:15,511 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:15,512 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:15,512 DEBUG --- stdout --- 2024-04-22 03:27:15,512 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:15,512 DEBUG --- stderr --- 2024-04-22 03:27:15,512 DEBUG 2024-04-22 03:27:15,512 INFO 2024-04-22 03:27:15,512 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-9500/cmd.txt 2024-04-22 03:27:16,349 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 03:27:16,351 DEBUG --- stdout --- 2024-04-22 03:27:16,351 DEBUG 4 /results/orders/authn-users-9500/cmd.txt 2024-04-22 03:27:16,351 DEBUG --- stderr --- 2024-04-22 03:27:16,351 DEBUG 2024-04-22 03:27:16,351 INFO - Download file (size 4k) 2024-04-22 03:27:16,352 INFO 2024-04-22 03:27:16,352 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-22 03:27:16,352 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:16,699 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:16,699 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:16,699 DEBUG --- stdout --- 2024-04-22 03:27:16,699 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:16,699 DEBUG --- stderr --- 2024-04-22 03:27:16,699 DEBUG source : /results/orders/authn-users-9500/cmd.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/cmd.txt 2024-04-22 03:27:16,700 INFO 2024-04-22 03:27:16,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-22 03:27:16,700 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:17,060 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:17,060 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:17,060 DEBUG --- stdout --- 2024-04-22 03:27:17,061 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:17,061 DEBUG --- stderr --- 2024-04-22 03:27:17,061 DEBUG 2024-04-22 03:27:17,061 INFO 2024-04-22 03:27:17,061 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-9500/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/cmd.txt 2024-04-22 03:27:17,061 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 03:27:17,819 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:17,820 DEBUG --- stdout --- 2024-04-22 03:27:17,820 DEBUG tar: Removing leading `/' from member names 2024-04-22 03:27:17,820 DEBUG --- stderr --- 2024-04-22 03:27:17,820 DEBUG 2024-04-22 03:27:17,820 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/cmd.txt is small (1 lines), printing all lines: 2024-04-22 03:27:17,820 DEBUG ----- output ----- 2024-04-22 03:27:17,820 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-9500 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-22 03:27:17,820 INFO ________________________________________________________________________________ [2024-04-22 03:27:17] authn-users-9500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-22 03:27:17,821 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/rc.txt is small (1 lines), printing all lines: 2024-04-22 03:27:17,821 DEBUG ----- output ----- 2024-04-22 03:27:17,821 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-22 03:27:17,821 INFO ________________________________________________________________________________ [2024-04-22 03:27:17] authn-users-9500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-22 03:27:17,822 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/05_authn-users-9500/stdout.txt is 8746 lines): 2024-04-22 03:27:17,822 DEBUG ----- output ----- 2024-04-22 03:27:17,822 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-22 03:27:17,822 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-22 03:27:17,822 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-22 03:27:17,822 INFO 2024-04-22 03:27:17,822 INFO ================================================================================ 2024-04-22 03:27:17,823 INFO 2024-04-22 03:24:50 5s elapsed 2024-04-22 03:27:17,823 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,823 INFO > Global (OK=3937 KO=0 ) 2024-04-22 03:27:17,823 INFO > userRestLogin (OK=3937 KO=0 ) 2024-04-22 03:27:17,823 INFO 2024-04-22 03:27:17,823 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,823 INFO [- ] 0% 2024-04-22 03:27:17,823 INFO waiting: 34349633 / active: 30 / done: 3937 2024-04-22 03:27:17,823 INFO ================================================================================ 2024-04-22 03:27:17,823 INFO 2024-04-22 03:27:17,823 INFO 2024-04-22 03:27:17,823 INFO ================================================================================ 2024-04-22 03:27:17,823 INFO 2024-04-22 03:24:55 10s elapsed 2024-04-22 03:27:17,823 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,823 INFO > Global (OK=15800 KO=0 ) 2024-04-22 03:27:17,823 INFO > userRestLogin (OK=15800 KO=0 ) 2024-04-22 03:27:17,823 INFO 2024-04-22 03:27:17,823 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,823 INFO [- ] 0% 2024-04-22 03:27:17,823 INFO waiting: 34337750 / active: 50 / done: 15800 2024-04-22 03:27:17,823 INFO ================================================================================ 2024-04-22 03:27:17,823 INFO 2024-04-22 03:27:17,823 INFO 2024-04-22 03:27:17,823 INFO ================================================================================ 2024-04-22 03:27:17,823 INFO 2024-04-22 03:25:00 15s elapsed 2024-04-22 03:27:17,823 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,823 INFO > Global (OK=35576 KO=0 ) 2024-04-22 03:27:17,823 INFO > userRestLogin (OK=35576 KO=0 ) 2024-04-22 03:27:17,823 INFO 2024-04-22 03:27:17,823 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,823 INFO [- ] 0% 2024-04-22 03:27:17,823 INFO waiting: 34317950 / active: 74 / done: 35576 2024-04-22 03:27:17,823 INFO ================================================================================ 2024-04-22 03:27:17,823 INFO 2024-04-22 03:27:17,823 INFO 2024-04-22 03:27:17,823 INFO ================================================================================ 2024-04-22 03:27:17,823 INFO 2024-04-22 03:25:05 20s elapsed 2024-04-22 03:27:17,823 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,823 INFO > Global (OK=63242 KO=0 ) 2024-04-22 03:27:17,823 INFO > userRestLogin (OK=63242 KO=0 ) 2024-04-22 03:27:17,823 INFO 2024-04-22 03:27:17,824 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,824 INFO [- ] 0% 2024-04-22 03:27:17,824 INFO waiting: 34290241 / active: 117 / done: 63242 2024-04-22 03:27:17,824 INFO ================================================================================ 2024-04-22 03:27:17,824 INFO 2024-04-22 03:27:17,824 INFO 2024-04-22 03:27:17,824 INFO ================================================================================ 2024-04-22 03:27:17,824 INFO 2024-04-22 03:25:10 25s elapsed 2024-04-22 03:27:17,824 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,824 INFO > Global (OK=98834 KO=0 ) 2024-04-22 03:27:17,824 INFO > userRestLogin (OK=98834 KO=0 ) 2024-04-22 03:27:17,824 INFO 2024-04-22 03:27:17,824 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,824 INFO [- ] 0% 2024-04-22 03:27:17,824 INFO waiting: 34254609 / active: 158 / done: 98833 2024-04-22 03:27:17,824 INFO ================================================================================ 2024-04-22 03:27:17,824 INFO 2024-04-22 03:27:17,824 INFO 2024-04-22 03:27:17,824 INFO ================================================================================ 2024-04-22 03:27:17,824 INFO 2024-04-22 03:25:15 30s elapsed 2024-04-22 03:27:17,824 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,824 INFO > Global (OK=142116 KO=0 ) 2024-04-22 03:27:17,824 INFO > userRestLogin (OK=142116 KO=0 ) 2024-04-22 03:27:17,824 INFO 2024-04-22 03:27:17,824 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,824 INFO [- ] 0% 2024-04-22 03:27:17,824 INFO waiting: 34211267 / active: 217 / done: 142116 2024-04-22 03:27:17,824 INFO ================================================================================ 2024-04-22 03:27:17,824 INFO 2024-04-22 03:27:17,824 INFO 2024-04-22 03:27:17,824 INFO ================================================================================ 2024-04-22 03:27:17,824 INFO 2024-04-22 03:25:20 35s elapsed 2024-04-22 03:27:17,824 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,824 INFO > Global (OK=189534 KO=0 ) 2024-04-22 03:27:17,824 INFO > userRestLogin (OK=189534 KO=0 ) 2024-04-22 03:27:17,824 INFO 2024-04-22 03:27:17,824 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,824 INFO [- ] 0% 2024-04-22 03:27:17,824 INFO waiting: 34163853 / active: 214 / done: 189533 2024-04-22 03:27:17,824 INFO ================================================================================ 2024-04-22 03:27:17,824 INFO 2024-04-22 03:27:17,824 INFO 2024-04-22 03:27:17,824 INFO ================================================================================ 2024-04-22 03:27:17,825 INFO 2024-04-22 03:25:25 40s elapsed 2024-04-22 03:27:17,825 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,825 INFO > Global (OK=236783 KO=0 ) 2024-04-22 03:27:17,825 INFO > userRestLogin (OK=236783 KO=0 ) 2024-04-22 03:27:17,825 INFO 2024-04-22 03:27:17,825 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,825 INFO [- ] 0% 2024-04-22 03:27:17,825 INFO waiting: 34116307 / active: 512 / done: 236781 2024-04-22 03:27:17,825 INFO ================================================================================ 2024-04-22 03:27:17,825 INFO 2024-04-22 03:27:17,825 INFO 2024-04-22 03:27:17,825 INFO [...] 2024-04-22 03:27:17,827 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,827 INFO > Global (OK=34113756 KO=0 ) 2024-04-22 03:27:17,827 INFO > userRestLogin (OK=34113756 KO=0 ) 2024-04-22 03:27:17,827 INFO 2024-04-22 03:27:17,827 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,827 INFO [#########################################################################-] 99% 2024-04-22 03:27:17,827 INFO waiting: 237587 / active: 2257 / done: 34113756 2024-04-22 03:27:17,827 INFO ================================================================================ 2024-04-22 03:27:17,827 INFO 2024-04-22 03:27:17,827 INFO 2024-04-22 03:27:17,827 INFO ================================================================================ 2024-04-22 03:27:17,827 INFO 2024-04-22 04:24:55 3610s elapsed 2024-04-22 03:27:17,827 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,827 INFO > Global (OK=34161626 KO=0 ) 2024-04-22 03:27:17,827 INFO > userRestLogin (OK=34161626 KO=0 ) 2024-04-22 03:27:17,827 INFO 2024-04-22 03:27:17,828 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,828 INFO [#########################################################################-] 99% 2024-04-22 03:27:17,828 INFO waiting: 190252 / active: 1723 / done: 34161625 2024-04-22 03:27:17,828 INFO ================================================================================ 2024-04-22 03:27:17,828 INFO 2024-04-22 03:27:17,828 INFO 2024-04-22 03:27:17,828 INFO ================================================================================ 2024-04-22 03:27:17,828 INFO 2024-04-22 04:25:00 3615s elapsed 2024-04-22 03:27:17,828 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,828 INFO > Global (OK=34209928 KO=0 ) 2024-04-22 03:27:17,828 INFO > userRestLogin (OK=34209928 KO=0 ) 2024-04-22 03:27:17,828 INFO 2024-04-22 03:27:17,828 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,828 INFO [#########################################################################-] 99% 2024-04-22 03:27:17,828 INFO waiting: 142836 / active: 838 / done: 34209926 2024-04-22 03:27:17,828 INFO ================================================================================ 2024-04-22 03:27:17,828 INFO 2024-04-22 03:27:17,828 INFO 2024-04-22 03:27:17,828 INFO ================================================================================ 2024-04-22 03:27:17,828 INFO 2024-04-22 04:25:05 3620s elapsed 2024-04-22 03:27:17,828 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,828 INFO > Global (OK=34256464 KO=0 ) 2024-04-22 03:27:17,828 INFO > userRestLogin (OK=34256464 KO=0 ) 2024-04-22 03:27:17,828 INFO 2024-04-22 03:27:17,828 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,828 INFO [#########################################################################-] 99% 2024-04-22 03:27:17,828 INFO waiting: 95223 / active: 1914 / done: 34256463 2024-04-22 03:27:17,828 INFO ================================================================================ 2024-04-22 03:27:17,828 INFO 2024-04-22 03:27:17,828 INFO 2024-04-22 03:27:17,828 INFO ================================================================================ 2024-04-22 03:27:17,828 INFO 2024-04-22 04:25:10 3625s elapsed 2024-04-22 03:27:17,828 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,828 INFO > Global (OK=34304494 KO=0 ) 2024-04-22 03:27:17,828 INFO > userRestLogin (OK=34304494 KO=0 ) 2024-04-22 03:27:17,828 INFO 2024-04-22 03:27:17,828 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,828 INFO [#########################################################################-] 99% 2024-04-22 03:27:17,828 INFO waiting: 47601 / active: 1506 / done: 34304493 2024-04-22 03:27:17,828 INFO ================================================================================ 2024-04-22 03:27:17,828 INFO 2024-04-22 03:27:17,829 INFO 2024-04-22 03:27:17,829 INFO ================================================================================ 2024-04-22 03:27:17,829 INFO 2024-04-22 04:25:15 3630s elapsed 2024-04-22 03:27:17,829 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,829 INFO > Global (OK=34352076 KO=0 ) 2024-04-22 03:27:17,829 INFO > userRestLogin (OK=34352076 KO=0 ) 2024-04-22 03:27:17,829 INFO 2024-04-22 03:27:17,829 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,829 INFO [#########################################################################-] 99% 2024-04-22 03:27:17,829 INFO waiting: 0 / active: 1525 / done: 34352075 2024-04-22 03:27:17,829 INFO ================================================================================ 2024-04-22 03:27:17,829 INFO 2024-04-22 03:27:17,829 INFO 2024-04-22 03:27:17,829 INFO ================================================================================ 2024-04-22 03:27:17,829 INFO 2024-04-22 04:25:16 3630s elapsed 2024-04-22 03:27:17,829 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 03:27:17,829 INFO > Global (OK=34353600 KO=0 ) 2024-04-22 03:27:17,829 INFO > userRestLogin (OK=34353600 KO=0 ) 2024-04-22 03:27:17,829 INFO 2024-04-22 03:27:17,829 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 03:27:17,829 INFO [##########################################################################]100% 2024-04-22 03:27:17,829 INFO waiting: 0 / active: 0 / done: 34353600 2024-04-22 03:27:17,829 INFO ================================================================================ 2024-04-22 03:27:17,829 INFO 2024-04-22 03:27:17,829 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-22 03:27:17,829 INFO 2024-04-22 03:27:17,829 INFO ---------------------- 2024-04-22 03:27:17,829 INFO [22/Apr/2024 04:25:16] Create tar file authn-users-9500.tar.gz 2024-04-22 03:27:17,829 INFO ---------------------- 2024-04-22 03:27:17,829 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-22 03:27:17,829 INFO RESULT_FOLDER=authn-users-9500 2024-04-22 03:27:17,829 INFO SIMULATION_PATH=authn-users-9500/loadtest-20240422022445143 2024-04-22 03:27:17,829 INFO PWD=/results/gatling/authn-users-9500/loadtest-20240422022445143 2024-04-22 03:27:17,829 INFO [cmd] tar -czvf /results/gatling//authn-users-9500.tar.gz --exclude=simulation.log * 2024-04-22 03:27:17,829 INFO /results/gatling 2024-04-22 03:27:17,829 INFO 2024-04-22 03:27:17,829 INFO ---------------------- 2024-04-22 03:27:17,829 INFO [22/Apr/2024 04:25:16] Update /results/gatling//latest 2024-04-22 03:27:17,829 INFO ---------------------- 2024-04-22 03:27:17,829 INFO 2024-04-22 03:27:17,829 INFO ---------------------- 2024-04-22 03:27:17,829 INFO [22/Apr/2024 04:25:16] Update /results/gatling//latest.tar.gz 2024-04-22 03:27:17,830 INFO ---------------------- 2024-04-22 03:27:17,830 INFO ________________________________________________________________________________ [2024-04-22 03:27:17] authn-users-9500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-22 03:27:17,830 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-9500/stderr.txt is small (0 lines), printing all lines: 2024-04-22 03:27:17,830 DEBUG ----- output ----- 2024-04-22 03:27:17,830 DEBUG 2024-04-22 03:27:17,830 INFO ________________________________________________________________________________ [2024-04-22 03:27:17] authn-users-9500 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-22 03:27:17] authn-users-9500 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-22 03:27:17] authn-users-9500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped