--Task-- name: authn-users-7500 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-7000'] preceding_task: authn-users-7000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 7500, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-20 01:19:04] authn-users-7500 pre : N/A ________________________________________________________________________________ [2024-04-20 01:19:04] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-20 01:19:04] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-7500/stdout.txt Pod log : /results/orders/authn-users-7500 2024-04-20 01:19:04,108 INFO 2024-04-20 01:19:04,108 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 01:19:04,108 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:19:04,427 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:19:04,427 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:04,427 DEBUG --- stdout --- 2024-04-20 01:19:04,427 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:19:04,427 DEBUG --- stderr --- 2024-04-20 01:19:04,427 DEBUG ________________________________________________________________________________ [2024-04-20 01:19:04] authn-users-7500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-20 01:19:04,428 INFO 2024-04-20 01:19:04,428 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 01:19:04,428 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:19:04,755 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:19:04,755 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:04,755 DEBUG --- stdout --- 2024-04-20 01:19:04,756 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:19:04,756 DEBUG --- stderr --- 2024-04-20 01:19:04,756 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-7500 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-20 01:19:04] authn-users-7500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-20 01:19:04,756 INFO 2024-04-20 01:19:04,756 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 01:19:04,756 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:19:05,091 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:19:05,091 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:05,091 DEBUG --- stdout --- 2024-04-20 01:19:05,091 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:19:05,091 DEBUG --- stderr --- 2024-04-20 01:19:05,092 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/order.json { "name": "authn-users-7500", "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-7500 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-7500", "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=7500 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-20 01:19:05] authn-users-7500 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-20 01:19:05,093 INFO 2024-04-20 01:19:05,093 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-20 01:19:05,093 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-20 01:19:05,319 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 01:19:05,319 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:05,319 DEBUG --- stdout --- 2024-04-20 01:19:05,320 DEBUG WAITING 2024-04-20 01:19:05,320 DEBUG --- stderr --- 2024-04-20 01:19:05,320 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-20 01:19:05] authn-users-7500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-20 01:19:05,320 INFO 2024-04-20 01:19:05,320 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 01:19:05,320 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:19:05,623 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:19:05,624 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:05,624 DEBUG --- stdout --- 2024-04-20 01:19:05,624 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:19:05,624 DEBUG --- stderr --- 2024-04-20 01:19:05,624 DEBUG 2024-04-20 01:19:05,624 INFO 2024-04-20 01:19:05,624 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-7500/order.json overseer-0-5c67bf94db-77mh9:/results/orders/order.json 2024-04-20 01:19:05,624 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 01:19:06,998 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:06,998 DEBUG --- stdout --- 2024-04-20 01:19:06,998 DEBUG 2024-04-20 01:19:06,999 DEBUG --- stderr --- 2024-04-20 01:19:06,999 DEBUG Order has been successfully forwarded to overseer [2024-04-20 01:19:06] - INFO: Check if Gatling simulation started successfully 2024-04-20 01:19:06,999 INFO 2024-04-20 01:19:06,999 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 01:19:06,999 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 01:19:07,312 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 01:19:07,312 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:07,312 DEBUG --- stdout --- 2024-04-20 01:19:07,312 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 01:19:07,312 DEBUG --- stderr --- 2024-04-20 01:19:07,312 DEBUG 2024-04-20 01:19:07,313 INFO 2024-04-20 01:19:07,313 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-7500/stdout.txt | grep "started..." 2024-04-20 01:19:07,313 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-20 01:19:08,082 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-20 01:19:13,883 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-20 01:19:19,641 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-20 01:19:19,641 INFO [loop_until]: OK (rc = 0) 2024-04-20 01:19:19,641 DEBUG --- stdout --- 2024-04-20 01:19:19,641 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-20 01:19:19,641 DEBUG --- stderr --- 2024-04-20 01:19:19,641 DEBUG ________________________________________________________________________________ [2024-04-20 01:19:19] authn-users-7500 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-20 02:21:30] authn-users-7500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-20 02:21:30,055 INFO 2024-04-20 02:21:30,055 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 02:21:30,055 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:30,378 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:30,379 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:30,379 DEBUG --- stdout --- 2024-04-20 02:21:30,379 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:30,379 DEBUG --- stderr --- 2024-04-20 02:21:30,379 DEBUG 2024-04-20 02:21:30,379 INFO 2024-04-20 02:21:30,379 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 02:21:30,379 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 02:21:31,168 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:31,168 DEBUG --- stdout --- 2024-04-20 02:21:31,169 DEBUG 2024-04-20 02:21:31,169 DEBUG --- stderr --- 2024-04-20 02:21:31,169 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-20 02:21:31] authn-users-7500 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-20 02:21:31] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/order.json 2024-04-20 02:21:31,169 INFO 2024-04-20 02:21:31,169 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 02:21:31,169 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:31,467 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:31,467 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:31,467 DEBUG --- stdout --- 2024-04-20 02:21:31,467 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:31,467 DEBUG --- stderr --- 2024-04-20 02:21:31,467 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 02:21:31,468 INFO 2024-04-20 02:21:31,468 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 02:21:31,468 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:31,763 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:31,763 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:31,763 DEBUG --- stdout --- 2024-04-20 02:21:31,763 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:31,763 DEBUG --- stderr --- 2024-04-20 02:21:31,763 DEBUG 2024-04-20 02:21:31,764 INFO 2024-04-20 02:21:31,764 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-7500/rc.txt" ]; then echo "/results/orders/authn-users-7500/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-7500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7500/rc.txt file found" 2024-04-20 02:21:31,764 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 02:21:32,579 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 02:21:32,579 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:32,579 DEBUG --- stdout --- 2024-04-20 02:21:32,579 DEBUG /results/orders/authn-users-7500/rc.txt file found 2024-04-20 02:21:32,579 DEBUG --- stderr --- 2024-04-20 02:21:32,579 DEBUG 2024-04-20 02:21:32,580 INFO 2024-04-20 02:21:32,580 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 02:21:32,580 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:32,889 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:32,890 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:32,890 DEBUG --- stdout --- 2024-04-20 02:21:32,890 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:32,890 DEBUG --- stderr --- 2024-04-20 02:21:32,890 DEBUG 2024-04-20 02:21:32,890 INFO 2024-04-20 02:21:32,891 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-7500/rc.txt 2024-04-20 02:21:33,729 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 02:21:33,730 DEBUG --- stdout --- 2024-04-20 02:21:33,731 DEBUG 4 /results/orders/authn-users-7500/rc.txt 2024-04-20 02:21:33,731 DEBUG --- stderr --- 2024-04-20 02:21:33,731 DEBUG 2024-04-20 02:21:33,731 INFO - Download file (size 4k) 2024-04-20 02:21:33,732 INFO 2024-04-20 02:21:33,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-20 02:21:33,732 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:34,057 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:34,057 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:34,058 DEBUG --- stdout --- 2024-04-20 02:21:34,058 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:34,058 DEBUG --- stderr --- 2024-04-20 02:21:34,058 DEBUG source : /results/orders/authn-users-7500/rc.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/rc.txt 2024-04-20 02:21:34,058 INFO 2024-04-20 02:21:34,058 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 02:21:34,058 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:34,386 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:34,386 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:34,386 DEBUG --- stdout --- 2024-04-20 02:21:34,386 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:34,386 DEBUG --- stderr --- 2024-04-20 02:21:34,386 DEBUG 2024-04-20 02:21:34,386 INFO 2024-04-20 02:21:34,386 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-7500/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/rc.txt 2024-04-20 02:21:34,386 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 02:21:35,129 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:35,129 DEBUG --- stdout --- 2024-04-20 02:21:35,129 DEBUG tar: Removing leading `/' from member names 2024-04-20 02:21:35,129 DEBUG --- stderr --- 2024-04-20 02:21:35,129 DEBUG 2024-04-20 02:21:35,129 INFO 2024-04-20 02:21:35,129 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-20 02:21:35,129 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:35,426 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:35,426 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:35,426 DEBUG --- stdout --- 2024-04-20 02:21:35,426 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:35,426 DEBUG --- stderr --- 2024-04-20 02:21:35,426 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 02:21:35,426 INFO 2024-04-20 02:21:35,426 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 02:21:35,427 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:35,727 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:35,728 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:35,728 DEBUG --- stdout --- 2024-04-20 02:21:35,728 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:35,728 DEBUG --- stderr --- 2024-04-20 02:21:35,728 DEBUG 2024-04-20 02:21:35,728 INFO 2024-04-20 02:21:35,728 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-7500/stderr.txt" ]; then echo "/results/orders/authn-users-7500/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-7500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7500/stderr.txt file found" 2024-04-20 02:21:35,729 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 02:21:36,543 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 02:21:36,544 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:36,544 DEBUG --- stdout --- 2024-04-20 02:21:36,544 DEBUG /results/orders/authn-users-7500/stderr.txt file found 2024-04-20 02:21:36,544 DEBUG --- stderr --- 2024-04-20 02:21:36,544 DEBUG 2024-04-20 02:21:36,544 INFO 2024-04-20 02:21:36,544 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 02:21:36,544 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:36,860 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:36,860 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:36,860 DEBUG --- stdout --- 2024-04-20 02:21:36,860 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:36,861 DEBUG --- stderr --- 2024-04-20 02:21:36,861 DEBUG 2024-04-20 02:21:36,861 INFO 2024-04-20 02:21:36,861 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-7500/stderr.txt 2024-04-20 02:21:37,687 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 02:21:37,687 DEBUG --- stdout --- 2024-04-20 02:21:37,687 DEBUG 0 /results/orders/authn-users-7500/stderr.txt 2024-04-20 02:21:37,687 DEBUG --- stderr --- 2024-04-20 02:21:37,687 DEBUG 2024-04-20 02:21:37,687 INFO - Download file (size 0k) 2024-04-20 02:21:37,687 INFO 2024-04-20 02:21:37,688 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 02:21:37,688 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:37,984 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:37,984 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:37,984 DEBUG --- stdout --- 2024-04-20 02:21:37,985 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:37,985 DEBUG --- stderr --- 2024-04-20 02:21:37,985 DEBUG source : /results/orders/authn-users-7500/stderr.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/stderr.txt 2024-04-20 02:21:37,985 INFO 2024-04-20 02:21:37,985 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 02:21:37,985 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:38,285 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:38,285 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:38,285 DEBUG --- stdout --- 2024-04-20 02:21:38,285 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:38,285 DEBUG --- stderr --- 2024-04-20 02:21:38,285 DEBUG 2024-04-20 02:21:38,286 INFO 2024-04-20 02:21:38,286 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-7500/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/stderr.txt 2024-04-20 02:21:38,286 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 02:21:39,058 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:39,058 DEBUG --- stdout --- 2024-04-20 02:21:39,058 DEBUG tar: Removing leading `/' from member names 2024-04-20 02:21:39,058 DEBUG --- stderr --- 2024-04-20 02:21:39,059 DEBUG 2024-04-20 02:21:39,059 INFO 2024-04-20 02:21:39,059 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 02:21:39,059 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:39,372 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:39,372 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:39,372 DEBUG --- stdout --- 2024-04-20 02:21:39,372 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:39,373 DEBUG --- stderr --- 2024-04-20 02:21:39,373 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 02:21:39,373 INFO 2024-04-20 02:21:39,373 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 02:21:39,373 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:39,708 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:39,708 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:39,708 DEBUG --- stdout --- 2024-04-20 02:21:39,708 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:39,708 DEBUG --- stderr --- 2024-04-20 02:21:39,708 DEBUG 2024-04-20 02:21:39,709 INFO 2024-04-20 02:21:39,709 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-7500/stdout.txt" ]; then echo "/results/orders/authn-users-7500/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-7500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7500/stdout.txt file found" 2024-04-20 02:21:39,709 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 02:21:40,523 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 02:21:40,523 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:40,523 DEBUG --- stdout --- 2024-04-20 02:21:40,523 DEBUG /results/orders/authn-users-7500/stdout.txt file found 2024-04-20 02:21:40,523 DEBUG --- stderr --- 2024-04-20 02:21:40,523 DEBUG 2024-04-20 02:21:40,523 INFO 2024-04-20 02:21:40,523 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 02:21:40,523 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:40,829 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:40,829 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:40,829 DEBUG --- stdout --- 2024-04-20 02:21:40,829 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:40,829 DEBUG --- stderr --- 2024-04-20 02:21:40,829 DEBUG 2024-04-20 02:21:40,829 INFO 2024-04-20 02:21:40,829 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-7500/stdout.txt 2024-04-20 02:21:41,645 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 02:21:41,645 DEBUG --- stdout --- 2024-04-20 02:21:41,645 DEBUG 508 /results/orders/authn-users-7500/stdout.txt 2024-04-20 02:21:41,645 DEBUG --- stderr --- 2024-04-20 02:21:41,645 DEBUG 2024-04-20 02:21:41,645 INFO - Download file (size 508k) 2024-04-20 02:21:41,646 INFO 2024-04-20 02:21:41,646 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-20 02:21:41,646 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:41,965 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:41,966 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:41,966 DEBUG --- stdout --- 2024-04-20 02:21:41,966 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:41,966 DEBUG --- stderr --- 2024-04-20 02:21:41,966 DEBUG source : /results/orders/authn-users-7500/stdout.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/stdout.txt 2024-04-20 02:21:41,966 INFO 2024-04-20 02:21:41,966 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 02:21:41,966 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:42,277 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:42,277 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:42,277 DEBUG --- stdout --- 2024-04-20 02:21:42,277 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:42,277 DEBUG --- stderr --- 2024-04-20 02:21:42,277 DEBUG 2024-04-20 02:21:42,278 INFO 2024-04-20 02:21:42,278 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-7500/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/stdout.txt 2024-04-20 02:21:42,278 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 02:21:43,348 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:43,348 DEBUG --- stdout --- 2024-04-20 02:21:43,348 DEBUG tar: Removing leading `/' from member names 2024-04-20 02:21:43,348 DEBUG --- stderr --- 2024-04-20 02:21:43,348 DEBUG ________________________________________________________________________________ [2024-04-20 02:21:43] authn-users-7500 show_cmd : Display order command ________________________________________________________________________________ 2024-04-20 02:21:43,349 INFO 2024-04-20 02:21:43,349 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 02:21:43,349 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:43,663 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:43,663 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:43,663 DEBUG --- stdout --- 2024-04-20 02:21:43,663 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:43,663 DEBUG --- stderr --- 2024-04-20 02:21:43,663 DEBUG Checking pod: overseer-0-5c67bf94db-77mh9 for file exists, if not wait. 2024-04-20 02:21:43,664 INFO 2024-04-20 02:21:43,664 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 02:21:43,664 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:43,975 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:43,975 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:43,975 DEBUG --- stdout --- 2024-04-20 02:21:43,976 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:43,976 DEBUG --- stderr --- 2024-04-20 02:21:43,976 DEBUG 2024-04-20 02:21:43,977 INFO 2024-04-20 02:21:43,977 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-7500/cmd.txt" ]; then echo "/results/orders/authn-users-7500/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-7500/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-7500/cmd.txt file found" 2024-04-20 02:21:43,977 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-20 02:21:44,844 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-20 02:21:44,845 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:44,845 DEBUG --- stdout --- 2024-04-20 02:21:44,845 DEBUG /results/orders/authn-users-7500/cmd.txt file found 2024-04-20 02:21:44,845 DEBUG --- stderr --- 2024-04-20 02:21:44,845 DEBUG 2024-04-20 02:21:44,846 INFO 2024-04-20 02:21:44,846 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-20 02:21:44,846 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:45,176 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:45,177 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:45,177 DEBUG --- stdout --- 2024-04-20 02:21:45,177 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:45,177 DEBUG --- stderr --- 2024-04-20 02:21:45,177 DEBUG 2024-04-20 02:21:45,177 INFO 2024-04-20 02:21:45,178 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-7500/cmd.txt 2024-04-20 02:21:46,013 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-20 02:21:46,013 DEBUG --- stdout --- 2024-04-20 02:21:46,013 DEBUG 4 /results/orders/authn-users-7500/cmd.txt 2024-04-20 02:21:46,013 DEBUG --- stderr --- 2024-04-20 02:21:46,013 DEBUG 2024-04-20 02:21:46,013 INFO - Download file (size 4k) 2024-04-20 02:21:46,014 INFO 2024-04-20 02:21:46,014 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 02:21:46,014 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:46,353 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:46,353 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:46,353 DEBUG --- stdout --- 2024-04-20 02:21:46,353 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:46,353 DEBUG --- stderr --- 2024-04-20 02:21:46,353 DEBUG source : /results/orders/authn-users-7500/cmd.txt @ overseer-0-5c67bf94db-77mh9 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/cmd.txt 2024-04-20 02:21:46,353 INFO 2024-04-20 02:21:46,353 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 02:21:46,353 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-20 02:21:46,653 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-20 02:21:46,653 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:46,653 DEBUG --- stdout --- 2024-04-20 02:21:46,653 DEBUG overseer-0-5c67bf94db-77mh9 2024-04-20 02:21:46,653 DEBUG --- stderr --- 2024-04-20 02:21:46,653 DEBUG 2024-04-20 02:21:46,654 INFO 2024-04-20 02:21:46,654 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-7500/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/cmd.txt 2024-04-20 02:21:46,654 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-20 02:21:47,432 INFO [loop_until]: OK (rc = 0) 2024-04-20 02:21:47,432 DEBUG --- stdout --- 2024-04-20 02:21:47,432 DEBUG tar: Removing leading `/' from member names 2024-04-20 02:21:47,432 DEBUG --- stderr --- 2024-04-20 02:21:47,433 DEBUG 2024-04-20 02:21:47,433 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/cmd.txt is small (1 lines), printing all lines: 2024-04-20 02:21:47,433 DEBUG ----- output ----- 2024-04-20 02:21:47,433 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-7500 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-20 02:21:47,433 INFO ________________________________________________________________________________ [2024-04-20 02:21:47] authn-users-7500 show_rc : Display rc order ________________________________________________________________________________ 2024-04-20 02:21:47,433 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/rc.txt is small (1 lines), printing all lines: 2024-04-20 02:21:47,433 DEBUG ----- output ----- 2024-04-20 02:21:47,433 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-20 02:21:47,433 INFO ________________________________________________________________________________ [2024-04-20 02:21:47] authn-users-7500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-20 02:21:47,435 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-7500/stdout.txt is 8746 lines): 2024-04-20 02:21:47,435 DEBUG ----- output ----- 2024-04-20 02:21:47,435 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-20 02:21:47,435 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-20 02:21:47,435 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-20 02:21:47,435 INFO 2024-04-20 02:21:47,435 INFO ================================================================================ 2024-04-20 02:21:47,435 INFO 2024-04-20 02:19:20 5s elapsed 2024-04-20 02:21:47,435 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,435 INFO > Global (OK=3124 KO=0 ) 2024-04-20 02:21:47,435 INFO > userRestLogin (OK=3124 KO=0 ) 2024-04-20 02:21:47,435 INFO 2024-04-20 02:21:47,435 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,435 INFO [- ] 0% 2024-04-20 02:21:47,435 INFO waiting: 27107813 / active: 14 / done: 3124 2024-04-20 02:21:47,435 INFO ================================================================================ 2024-04-20 02:21:47,435 INFO 2024-04-20 02:21:47,435 INFO 2024-04-20 02:21:47,435 INFO ================================================================================ 2024-04-20 02:21:47,435 INFO 2024-04-20 02:19:25 10s elapsed 2024-04-20 02:21:47,435 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,435 INFO > Global (OK=12489 KO=0 ) 2024-04-20 02:21:47,435 INFO > userRestLogin (OK=12489 KO=0 ) 2024-04-20 02:21:47,435 INFO 2024-04-20 02:21:47,435 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,435 INFO [- ] 0% 2024-04-20 02:21:47,435 INFO waiting: 27098430 / active: 34 / done: 12487 2024-04-20 02:21:47,436 INFO ================================================================================ 2024-04-20 02:21:47,436 INFO 2024-04-20 02:21:47,436 INFO 2024-04-20 02:21:47,436 INFO ================================================================================ 2024-04-20 02:21:47,436 INFO 2024-04-20 02:19:30 15s elapsed 2024-04-20 02:21:47,436 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,436 INFO > Global (OK=28102 KO=0 ) 2024-04-20 02:21:47,436 INFO > userRestLogin (OK=28102 KO=0 ) 2024-04-20 02:21:47,436 INFO 2024-04-20 02:21:47,436 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,436 INFO [- ] 0% 2024-04-20 02:21:47,436 INFO waiting: 27082795 / active: 54 / done: 28102 2024-04-20 02:21:47,436 INFO ================================================================================ 2024-04-20 02:21:47,436 INFO 2024-04-20 02:21:47,436 INFO 2024-04-20 02:21:47,436 INFO ================================================================================ 2024-04-20 02:21:47,436 INFO 2024-04-20 02:19:35 20s elapsed 2024-04-20 02:21:47,436 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,436 INFO > Global (OK=49906 KO=0 ) 2024-04-20 02:21:47,436 INFO > userRestLogin (OK=49906 KO=0 ) 2024-04-20 02:21:47,436 INFO 2024-04-20 02:21:47,436 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,436 INFO [- ] 0% 2024-04-20 02:21:47,436 INFO waiting: 27060910 / active: 135 / done: 49906 2024-04-20 02:21:47,436 INFO ================================================================================ 2024-04-20 02:21:47,436 INFO 2024-04-20 02:21:47,436 INFO 2024-04-20 02:21:47,436 INFO ================================================================================ 2024-04-20 02:21:47,436 INFO 2024-04-20 02:19:40 25s elapsed 2024-04-20 02:21:47,436 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,436 INFO > Global (OK=78072 KO=0 ) 2024-04-20 02:21:47,436 INFO > userRestLogin (OK=78072 KO=0 ) 2024-04-20 02:21:47,436 INFO 2024-04-20 02:21:47,436 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,436 INFO [- ] 0% 2024-04-20 02:21:47,436 INFO waiting: 27032775 / active: 106 / done: 78070 2024-04-20 02:21:47,436 INFO ================================================================================ 2024-04-20 02:21:47,436 INFO 2024-04-20 02:21:47,436 INFO 2024-04-20 02:21:47,436 INFO ================================================================================ 2024-04-20 02:21:47,436 INFO 2024-04-20 02:19:45 30s elapsed 2024-04-20 02:21:47,436 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,437 INFO > Global (OK=112308 KO=0 ) 2024-04-20 02:21:47,437 INFO > userRestLogin (OK=112308 KO=0 ) 2024-04-20 02:21:47,437 INFO 2024-04-20 02:21:47,437 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,437 INFO [- ] 0% 2024-04-20 02:21:47,437 INFO waiting: 26998430 / active: 213 / done: 112308 2024-04-20 02:21:47,437 INFO ================================================================================ 2024-04-20 02:21:47,437 INFO 2024-04-20 02:21:47,437 INFO 2024-04-20 02:21:47,437 INFO ================================================================================ 2024-04-20 02:21:47,437 INFO 2024-04-20 02:19:50 35s elapsed 2024-04-20 02:21:47,437 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,437 INFO > Global (OK=149461 KO=0 ) 2024-04-20 02:21:47,437 INFO > userRestLogin (OK=149461 KO=0 ) 2024-04-20 02:21:47,437 INFO 2024-04-20 02:21:47,437 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,437 INFO [- ] 0% 2024-04-20 02:21:47,437 INFO waiting: 26961207 / active: 284 / done: 149460 2024-04-20 02:21:47,437 INFO ================================================================================ 2024-04-20 02:21:47,437 INFO 2024-04-20 02:21:47,437 INFO 2024-04-20 02:21:47,437 INFO ================================================================================ 2024-04-20 02:21:47,437 INFO 2024-04-20 02:19:55 40s elapsed 2024-04-20 02:21:47,437 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,437 INFO > Global (OK=187337 KO=0 ) 2024-04-20 02:21:47,437 INFO > userRestLogin (OK=187337 KO=0 ) 2024-04-20 02:21:47,437 INFO 2024-04-20 02:21:47,437 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,437 INFO [- ] 0% 2024-04-20 02:21:47,437 INFO waiting: 26923419 / active: 195 / done: 187337 2024-04-20 02:21:47,437 INFO ================================================================================ 2024-04-20 02:21:47,437 INFO 2024-04-20 02:21:47,437 INFO 2024-04-20 02:21:47,437 INFO [...] 2024-04-20 02:21:47,440 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,440 INFO > Global (OK=26923461 KO=0 ) 2024-04-20 02:21:47,440 INFO > userRestLogin (OK=26923461 KO=0 ) 2024-04-20 02:21:47,440 INFO 2024-04-20 02:21:47,440 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,440 INFO [#########################################################################-] 99% 2024-04-20 02:21:47,440 INFO waiting: 187313 / active: 177 / done: 26923461 2024-04-20 02:21:47,440 INFO ================================================================================ 2024-04-20 02:21:47,440 INFO 2024-04-20 02:21:47,440 INFO 2024-04-20 02:21:47,440 INFO ================================================================================ 2024-04-20 02:21:47,440 INFO 2024-04-20 03:19:25 3610s elapsed 2024-04-20 02:21:47,440 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,440 INFO > Global (OK=26960983 KO=0 ) 2024-04-20 02:21:47,440 INFO > userRestLogin (OK=26960983 KO=0 ) 2024-04-20 02:21:47,440 INFO 2024-04-20 02:21:47,440 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,440 INFO [#########################################################################-] 99% 2024-04-20 02:21:47,440 INFO waiting: 149771 / active: 198 / done: 26960982 2024-04-20 02:21:47,440 INFO ================================================================================ 2024-04-20 02:21:47,440 INFO 2024-04-20 02:21:47,440 INFO 2024-04-20 02:21:47,440 INFO ================================================================================ 2024-04-20 02:21:47,440 INFO 2024-04-20 03:19:30 3615s elapsed 2024-04-20 02:21:47,440 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,440 INFO > Global (OK=26998400 KO=0 ) 2024-04-20 02:21:47,440 INFO > userRestLogin (OK=26998400 KO=0 ) 2024-04-20 02:21:47,440 INFO 2024-04-20 02:21:47,440 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,440 INFO [#########################################################################-] 99% 2024-04-20 02:21:47,440 INFO waiting: 112366 / active: 186 / done: 26998399 2024-04-20 02:21:47,440 INFO ================================================================================ 2024-04-20 02:21:47,440 INFO 2024-04-20 02:21:47,440 INFO 2024-04-20 02:21:47,440 INFO ================================================================================ 2024-04-20 02:21:47,440 INFO 2024-04-20 03:19:35 3620s elapsed 2024-04-20 02:21:47,441 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,441 INFO > Global (OK=27035885 KO=0 ) 2024-04-20 02:21:47,441 INFO > userRestLogin (OK=27035885 KO=0 ) 2024-04-20 02:21:47,441 INFO 2024-04-20 02:21:47,441 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,441 INFO [#########################################################################-] 99% 2024-04-20 02:21:47,441 INFO waiting: 74865 / active: 201 / done: 27035885 2024-04-20 02:21:47,441 INFO ================================================================================ 2024-04-20 02:21:47,441 INFO 2024-04-20 02:21:47,441 INFO 2024-04-20 02:21:47,441 INFO ================================================================================ 2024-04-20 02:21:47,441 INFO 2024-04-20 03:19:40 3625s elapsed 2024-04-20 02:21:47,441 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,441 INFO > Global (OK=27073392 KO=0 ) 2024-04-20 02:21:47,441 INFO > userRestLogin (OK=27073392 KO=0 ) 2024-04-20 02:21:47,441 INFO 2024-04-20 02:21:47,441 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,441 INFO [#########################################################################-] 99% 2024-04-20 02:21:47,441 INFO waiting: 37332 / active: 227 / done: 27073392 2024-04-20 02:21:47,441 INFO ================================================================================ 2024-04-20 02:21:47,441 INFO 2024-04-20 02:21:47,441 INFO 2024-04-20 02:21:47,441 INFO ================================================================================ 2024-04-20 02:21:47,441 INFO 2024-04-20 03:19:45 3630s elapsed 2024-04-20 02:21:47,441 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,441 INFO > Global (OK=27110820 KO=0 ) 2024-04-20 02:21:47,441 INFO > userRestLogin (OK=27110820 KO=0 ) 2024-04-20 02:21:47,441 INFO 2024-04-20 02:21:47,441 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,441 INFO [#########################################################################-] 99% 2024-04-20 02:21:47,441 INFO waiting: 0 / active: 131 / done: 27110820 2024-04-20 02:21:47,441 INFO ================================================================================ 2024-04-20 02:21:47,441 INFO 2024-04-20 02:21:47,441 INFO 2024-04-20 02:21:47,441 INFO ================================================================================ 2024-04-20 02:21:47,441 INFO 2024-04-20 03:19:45 3630s elapsed 2024-04-20 02:21:47,441 INFO ---- Requests ------------------------------------------------------------------ 2024-04-20 02:21:47,441 INFO > Global (OK=27110951 KO=0 ) 2024-04-20 02:21:47,441 INFO > userRestLogin (OK=27110951 KO=0 ) 2024-04-20 02:21:47,441 INFO 2024-04-20 02:21:47,441 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-20 02:21:47,442 INFO [##########################################################################]100% 2024-04-20 02:21:47,442 INFO waiting: 0 / active: 0 / done: 27110951 2024-04-20 02:21:47,442 INFO ================================================================================ 2024-04-20 02:21:47,442 INFO 2024-04-20 02:21:47,442 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-20 02:21:47,442 INFO 2024-04-20 02:21:47,442 INFO ---------------------- 2024-04-20 02:21:47,442 INFO [20/Apr/2024 03:19:45] Create tar file authn-users-7500.tar.gz 2024-04-20 02:21:47,442 INFO ---------------------- 2024-04-20 02:21:47,442 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-20 02:21:47,442 INFO RESULT_FOLDER=authn-users-7500 2024-04-20 02:21:47,442 INFO SIMULATION_PATH=authn-users-7500/loadtest-20240420011914550 2024-04-20 02:21:47,442 INFO PWD=/results/gatling/authn-users-7500/loadtest-20240420011914550 2024-04-20 02:21:47,442 INFO [cmd] tar -czvf /results/gatling//authn-users-7500.tar.gz --exclude=simulation.log * 2024-04-20 02:21:47,442 INFO /results/gatling 2024-04-20 02:21:47,442 INFO 2024-04-20 02:21:47,442 INFO ---------------------- 2024-04-20 02:21:47,442 INFO [20/Apr/2024 03:19:45] Update /results/gatling//latest 2024-04-20 02:21:47,442 INFO ---------------------- 2024-04-20 02:21:47,442 INFO 2024-04-20 02:21:47,442 INFO ---------------------- 2024-04-20 02:21:47,442 INFO [20/Apr/2024 03:19:45] Update /results/gatling//latest.tar.gz 2024-04-20 02:21:47,442 INFO ---------------------- 2024-04-20 02:21:47,442 INFO ________________________________________________________________________________ [2024-04-20 02:21:47] authn-users-7500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-20 02:21:47,443 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/05_authn-users-7500/stderr.txt is small (0 lines), printing all lines: 2024-04-20 02:21:47,443 DEBUG ----- output ----- 2024-04-20 02:21:47,443 DEBUG 2024-04-20 02:21:47,443 INFO ________________________________________________________________________________ [2024-04-20 02:21:47] authn-users-7500 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 02:21:47] authn-users-7500 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-20 02:21:47] authn-users-7500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped