--Task-- name: authn-users-6000 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['authn-users-5500'] preceding_task: authn-users-5500 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 6000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-14 22:49:33] authn-users-6000 pre : N/A ________________________________________________________________________________ [2024-04-14 22:49:33] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-14 22:49:33] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-6000/stdout.txt Pod log : /results/orders/authn-users-6000 2024-04-14 22:49:33,260 INFO 2024-04-14 22:49:33,261 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-14 22:49:33,261 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:33,596 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:33,597 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:33,597 DEBUG --- stdout --- 2024-04-14 22:49:33,597 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:33,597 DEBUG --- stderr --- 2024-04-14 22:49:33,597 DEBUG ________________________________________________________________________________ [2024-04-14 22:49:33] authn-users-6000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-14 22:49:33,598 INFO 2024-04-14 22:49:33,598 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-14 22:49:33,598 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:33,919 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:33,920 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:33,920 DEBUG --- stdout --- 2024-04-14 22:49:33,920 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:33,920 DEBUG --- stderr --- 2024-04-14 22:49:33,920 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "am.AMAuthN@medium" --results-folder /results/gatling/authn-users-6000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-14 22:49:33] authn-users-6000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-14 22:49:33,920 INFO 2024-04-14 22:49:33,920 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-14 22:49:33,921 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:34,238 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:34,239 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:34,239 DEBUG --- stdout --- 2024-04-14 22:49:34,239 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:34,239 DEBUG --- stderr --- 2024-04-14 22:49:34,239 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/order.json { "name": "authn-users-6000", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"am.AMAuthN@medium\" --results-folder /results/gatling/authn-users-6000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-6000", "environment": { "JAVA_OPTS": "-Dam_host=xlou.iam.xlou-cdm.engineeringpit.com -DamadminPassword=**** -DamadminUsername=amadmin -Dduration=3600 -Dnum_users=1000000 -DtestCase=am.AMAuthN -DtestMode=open -Dthroughput=6000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-14 22:49:34] authn-users-6000 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-04-14 22:49:34,240 INFO 2024-04-14 22:49:34,241 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-14 22:49:34,241 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 22:49:34,484 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 22:49:34,484 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:34,484 DEBUG --- stdout --- 2024-04-14 22:49:34,485 DEBUG WAITING 2024-04-14 22:49:34,485 DEBUG --- stderr --- 2024-04-14 22:49:34,485 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-14 22:49:34] authn-users-6000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-14 22:49:34,485 INFO 2024-04-14 22:49:34,485 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-14 22:49:34,485 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:34,809 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:34,810 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:34,810 DEBUG --- stdout --- 2024-04-14 22:49:34,810 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:34,810 DEBUG --- stderr --- 2024-04-14 22:49:34,810 DEBUG 2024-04-14 22:49:34,810 INFO 2024-04-14 22:49:34,810 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/06_authn-users-6000/order.json overseer-0-5584f6c4db-bcn5q:/results/orders/order.json 2024-04-14 22:49:34,811 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 22:49:36,229 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:36,230 DEBUG --- stdout --- 2024-04-14 22:49:36,230 DEBUG 2024-04-14 22:49:36,230 DEBUG --- stderr --- 2024-04-14 22:49:36,230 DEBUG Order has been successfully forwarded to overseer [2024-04-14 22:49:36] - INFO: Check if Gatling simulation started successfully 2024-04-14 22:49:36,230 INFO 2024-04-14 22:49:36,230 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-14 22:49:36,230 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 22:49:36,534 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 22:49:36,535 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:36,536 DEBUG --- stdout --- 2024-04-14 22:49:36,536 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 22:49:36,536 DEBUG --- stderr --- 2024-04-14 22:49:36,536 DEBUG 2024-04-14 22:49:36,536 INFO 2024-04-14 22:49:36,536 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- head -n 25 /results/orders/authn-users-6000/stdout.txt | grep "started..." 2024-04-14 22:49:36,536 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 22:49:37,293 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-14 22:49:43,072 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-14 22:49:48,855 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-14 22:49:48,855 INFO [loop_until]: OK (rc = 0) 2024-04-14 22:49:48,855 DEBUG --- stdout --- 2024-04-14 22:49:48,855 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-14 22:49:48,855 DEBUG --- stderr --- 2024-04-14 22:49:48,855 DEBUG ________________________________________________________________________________ [2024-04-14 22:49:48] authn-users-6000 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 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-14 23:51:59] authn-users-6000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-14 23:51:59,443 INFO 2024-04-14 23:51:59,443 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-14 23:51:59,443 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:51:59,779 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:51:59,779 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:51:59,779 DEBUG --- stdout --- 2024-04-14 23:51:59,779 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:51:59,779 DEBUG --- stderr --- 2024-04-14 23:51:59,779 DEBUG 2024-04-14 23:51:59,779 INFO 2024-04-14 23:51:59,780 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- rm -f /results/orders/order.json 2024-04-14 23:51:59,780 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 23:52:00,615 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:00,615 DEBUG --- stdout --- 2024-04-14 23:52:00,615 DEBUG 2024-04-14 23:52:00,615 DEBUG --- stderr --- 2024-04-14 23:52:00,615 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-14 23:52:00] authn-users-6000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-14 23:52:00] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/order.json 2024-04-14 23:52:00,616 INFO 2024-04-14 23:52:00,616 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 23:52:00,616 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:00,943 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:00,944 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:00,944 DEBUG --- stdout --- 2024-04-14 23:52:00,944 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:00,944 DEBUG --- stderr --- 2024-04-14 23:52:00,944 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 23:52:00,945 INFO 2024-04-14 23:52:00,945 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-14 23:52:00,945 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:01,245 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:01,245 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:01,245 DEBUG --- stdout --- 2024-04-14 23:52:01,245 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:01,245 DEBUG --- stderr --- 2024-04-14 23:52:01,245 DEBUG 2024-04-14 23:52:01,246 INFO 2024-04-14 23:52:01,246 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6000/rc.txt" ]; then echo "/results/orders/authn-users-6000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/rc.txt file found" 2024-04-14 23:52:01,246 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 23:52:02,075 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 23:52:02,075 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:02,075 DEBUG --- stdout --- 2024-04-14 23:52:02,075 DEBUG /results/orders/authn-users-6000/rc.txt file found 2024-04-14 23:52:02,075 DEBUG --- stderr --- 2024-04-14 23:52:02,075 DEBUG 2024-04-14 23:52:02,075 INFO 2024-04-14 23:52:02,076 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-14 23:52:02,076 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:02,399 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:02,400 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:02,400 DEBUG --- stdout --- 2024-04-14 23:52:02,400 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:02,400 DEBUG --- stderr --- 2024-04-14 23:52:02,400 DEBUG 2024-04-14 23:52:02,400 INFO 2024-04-14 23:52:02,400 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6000/rc.txt 2024-04-14 23:52:03,245 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 23:52:03,245 DEBUG --- stdout --- 2024-04-14 23:52:03,245 DEBUG 4 /results/orders/authn-users-6000/rc.txt 2024-04-14 23:52:03,245 DEBUG --- stderr --- 2024-04-14 23:52:03,245 DEBUG 2024-04-14 23:52:03,245 INFO - Download file (size 4k) 2024-04-14 23:52:03,246 INFO 2024-04-14 23:52:03,246 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-14 23:52:03,246 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:03,581 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:03,581 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:03,581 DEBUG --- stdout --- 2024-04-14 23:52:03,582 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:03,582 DEBUG --- stderr --- 2024-04-14 23:52:03,582 DEBUG source : /results/orders/authn-users-6000/rc.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/rc.txt 2024-04-14 23:52:03,582 INFO 2024-04-14 23:52:03,582 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-14 23:52:03,582 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:03,912 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:03,913 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:03,913 DEBUG --- stdout --- 2024-04-14 23:52:03,913 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:03,913 DEBUG --- stderr --- 2024-04-14 23:52:03,913 DEBUG 2024-04-14 23:52:03,913 INFO 2024-04-14 23:52:03,913 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/rc.txt 2024-04-14 23:52:03,913 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 23:52:04,691 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:04,691 DEBUG --- stdout --- 2024-04-14 23:52:04,691 DEBUG tar: Removing leading `/' from member names 2024-04-14 23:52:04,691 DEBUG --- stderr --- 2024-04-14 23:52:04,691 DEBUG 2024-04-14 23:52:04,692 INFO 2024-04-14 23:52:04,692 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-14 23:52:04,692 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:05,059 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:05,059 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:05,059 DEBUG --- stdout --- 2024-04-14 23:52:05,059 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:05,059 DEBUG --- stderr --- 2024-04-14 23:52:05,059 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 23:52:05,060 INFO 2024-04-14 23:52:05,060 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-14 23:52:05,060 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:05,447 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:05,447 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:05,447 DEBUG --- stdout --- 2024-04-14 23:52:05,447 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:05,447 DEBUG --- stderr --- 2024-04-14 23:52:05,447 DEBUG 2024-04-14 23:52:05,448 INFO 2024-04-14 23:52:05,448 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6000/stderr.txt" ]; then echo "/results/orders/authn-users-6000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/stderr.txt file found" 2024-04-14 23:52:05,448 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 23:52:06,302 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 23:52:06,303 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:06,303 DEBUG --- stdout --- 2024-04-14 23:52:06,303 DEBUG /results/orders/authn-users-6000/stderr.txt file found 2024-04-14 23:52:06,303 DEBUG --- stderr --- 2024-04-14 23:52:06,303 DEBUG 2024-04-14 23:52:06,303 INFO 2024-04-14 23:52:06,304 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-14 23:52:06,304 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:06,647 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:06,647 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:06,647 DEBUG --- stdout --- 2024-04-14 23:52:06,647 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:06,647 DEBUG --- stderr --- 2024-04-14 23:52:06,647 DEBUG 2024-04-14 23:52:06,647 INFO 2024-04-14 23:52:06,648 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6000/stderr.txt 2024-04-14 23:52:07,535 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 23:52:07,535 DEBUG --- stdout --- 2024-04-14 23:52:07,535 DEBUG 0 /results/orders/authn-users-6000/stderr.txt 2024-04-14 23:52:07,535 DEBUG --- stderr --- 2024-04-14 23:52:07,535 DEBUG 2024-04-14 23:52:07,535 INFO - Download file (size 0k) 2024-04-14 23:52:07,536 INFO 2024-04-14 23:52:07,536 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-14 23:52:07,536 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:07,862 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:07,863 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:07,863 DEBUG --- stdout --- 2024-04-14 23:52:07,863 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:07,863 DEBUG --- stderr --- 2024-04-14 23:52:07,863 DEBUG source : /results/orders/authn-users-6000/stderr.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/stderr.txt 2024-04-14 23:52:07,863 INFO 2024-04-14 23:52:07,863 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-14 23:52:07,863 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:08,187 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:08,187 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:08,187 DEBUG --- stdout --- 2024-04-14 23:52:08,187 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:08,187 DEBUG --- stderr --- 2024-04-14 23:52:08,187 DEBUG 2024-04-14 23:52:08,188 INFO 2024-04-14 23:52:08,188 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/stderr.txt 2024-04-14 23:52:08,188 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 23:52:08,991 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:08,992 DEBUG --- stdout --- 2024-04-14 23:52:08,992 DEBUG tar: Removing leading `/' from member names 2024-04-14 23:52:08,992 DEBUG --- stderr --- 2024-04-14 23:52:08,992 DEBUG 2024-04-14 23:52:08,992 INFO 2024-04-14 23:52:08,992 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 23:52:08,992 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:09,320 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:09,320 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:09,320 DEBUG --- stdout --- 2024-04-14 23:52:09,320 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:09,320 DEBUG --- stderr --- 2024-04-14 23:52:09,321 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 23:52:09,321 INFO 2024-04-14 23:52:09,321 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-14 23:52:09,321 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:09,646 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:09,646 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:09,646 DEBUG --- stdout --- 2024-04-14 23:52:09,646 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:09,646 DEBUG --- stderr --- 2024-04-14 23:52:09,646 DEBUG 2024-04-14 23:52:09,647 INFO 2024-04-14 23:52:09,647 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6000/stdout.txt" ]; then echo "/results/orders/authn-users-6000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/stdout.txt file found" 2024-04-14 23:52:09,647 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 23:52:10,491 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 23:52:10,491 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:10,491 DEBUG --- stdout --- 2024-04-14 23:52:10,491 DEBUG /results/orders/authn-users-6000/stdout.txt file found 2024-04-14 23:52:10,491 DEBUG --- stderr --- 2024-04-14 23:52:10,491 DEBUG 2024-04-14 23:52:10,492 INFO 2024-04-14 23:52:10,492 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-14 23:52:10,492 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:10,812 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:10,812 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:10,812 DEBUG --- stdout --- 2024-04-14 23:52:10,812 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:10,812 DEBUG --- stderr --- 2024-04-14 23:52:10,812 DEBUG 2024-04-14 23:52:10,813 INFO 2024-04-14 23:52:10,813 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6000/stdout.txt 2024-04-14 23:52:11,665 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 23:52:11,665 DEBUG --- stdout --- 2024-04-14 23:52:11,666 DEBUG 532 /results/orders/authn-users-6000/stdout.txt 2024-04-14 23:52:11,666 DEBUG --- stderr --- 2024-04-14 23:52:11,666 DEBUG 2024-04-14 23:52:11,666 INFO - Download file (size 532k) 2024-04-14 23:52:11,666 INFO 2024-04-14 23:52:11,666 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-14 23:52:11,666 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:12,004 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:12,004 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:12,004 DEBUG --- stdout --- 2024-04-14 23:52:12,004 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:12,004 DEBUG --- stderr --- 2024-04-14 23:52:12,004 DEBUG source : /results/orders/authn-users-6000/stdout.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/stdout.txt 2024-04-14 23:52:12,004 INFO 2024-04-14 23:52:12,005 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 23:52:12,005 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:12,328 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:12,329 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:12,329 DEBUG --- stdout --- 2024-04-14 23:52:12,329 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:12,329 DEBUG --- stderr --- 2024-04-14 23:52:12,329 DEBUG 2024-04-14 23:52:12,330 INFO 2024-04-14 23:52:12,330 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/stdout.txt 2024-04-14 23:52:12,330 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 23:52:13,480 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:13,481 DEBUG --- stdout --- 2024-04-14 23:52:13,481 DEBUG tar: Removing leading `/' from member names 2024-04-14 23:52:13,481 DEBUG --- stderr --- 2024-04-14 23:52:13,481 DEBUG ________________________________________________________________________________ [2024-04-14 23:52:13] authn-users-6000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-14 23:52:13,481 INFO 2024-04-14 23:52:13,482 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-14 23:52:13,482 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:13,839 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:13,839 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:13,839 DEBUG --- stdout --- 2024-04-14 23:52:13,839 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:13,839 DEBUG --- stderr --- 2024-04-14 23:52:13,839 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 23:52:13,840 INFO 2024-04-14 23:52:13,840 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-14 23:52:13,840 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:14,170 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:14,170 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:14,170 DEBUG --- stdout --- 2024-04-14 23:52:14,170 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:14,170 DEBUG --- stderr --- 2024-04-14 23:52:14,170 DEBUG 2024-04-14 23:52:14,171 INFO 2024-04-14 23:52:14,171 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6000/cmd.txt" ]; then echo "/results/orders/authn-users-6000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/cmd.txt file found" 2024-04-14 23:52:14,171 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 23:52:15,009 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 23:52:15,010 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:15,010 DEBUG --- stdout --- 2024-04-14 23:52:15,010 DEBUG /results/orders/authn-users-6000/cmd.txt file found 2024-04-14 23:52:15,010 DEBUG --- stderr --- 2024-04-14 23:52:15,010 DEBUG 2024-04-14 23:52:15,010 INFO 2024-04-14 23:52:15,010 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 23:52:15,010 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:15,336 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:15,336 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:15,336 DEBUG --- stdout --- 2024-04-14 23:52:15,336 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:15,336 DEBUG --- stderr --- 2024-04-14 23:52:15,336 DEBUG 2024-04-14 23:52:15,336 INFO 2024-04-14 23:52:15,336 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6000/cmd.txt 2024-04-14 23:52:16,178 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 23:52:16,179 DEBUG --- stdout --- 2024-04-14 23:52:16,179 DEBUG 4 /results/orders/authn-users-6000/cmd.txt 2024-04-14 23:52:16,179 DEBUG --- stderr --- 2024-04-14 23:52:16,179 DEBUG 2024-04-14 23:52:16,179 INFO - Download file (size 4k) 2024-04-14 23:52:16,179 INFO 2024-04-14 23:52:16,179 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-14 23:52:16,179 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:16,484 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:16,484 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:16,484 DEBUG --- stdout --- 2024-04-14 23:52:16,485 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:16,485 DEBUG --- stderr --- 2024-04-14 23:52:16,485 DEBUG source : /results/orders/authn-users-6000/cmd.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/cmd.txt 2024-04-14 23:52:16,485 INFO 2024-04-14 23:52:16,485 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-14 23:52:16,485 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 23:52:16,785 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 23:52:16,786 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:16,786 DEBUG --- stdout --- 2024-04-14 23:52:16,786 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 23:52:16,787 DEBUG --- stderr --- 2024-04-14 23:52:16,787 DEBUG 2024-04-14 23:52:16,787 INFO 2024-04-14 23:52:16,787 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/cmd.txt 2024-04-14 23:52:16,787 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 23:52:17,533 INFO [loop_until]: OK (rc = 0) 2024-04-14 23:52:17,534 DEBUG --- stdout --- 2024-04-14 23:52:17,534 DEBUG tar: Removing leading `/' from member names 2024-04-14 23:52:17,534 DEBUG --- stderr --- 2024-04-14 23:52:17,534 DEBUG 2024-04-14 23:52:17,534 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/cmd.txt is small (1 lines), printing all lines: 2024-04-14 23:52:17,535 DEBUG ----- output ----- 2024-04-14 23:52:17,535 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "am.AMAuthN@medium" --results-folder /results/gatling/authn-users-6000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-14 23:52:17,535 INFO ________________________________________________________________________________ [2024-04-14 23:52:17] authn-users-6000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-14 23:52:17,535 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/rc.txt is small (1 lines), printing all lines: 2024-04-14 23:52:17,535 DEBUG ----- output ----- 2024-04-14 23:52:17,535 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-14 23:52:17,535 INFO ________________________________________________________________________________ [2024-04-14 23:52:17] authn-users-6000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-14 23:52:17,537 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/06_authn-users-6000/stdout.txt is 9141 lines): 2024-04-14 23:52:17,537 DEBUG ----- output ----- 2024-04-14 23:52:17,537 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-14 23:52:17,537 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-14 23:52:17,537 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-14 23:52:17,537 INFO 2024-04-14 23:52:17,537 INFO ================================================================================ 2024-04-14 23:52:17,537 INFO 2024-04-14 23:49:49 5s elapsed 2024-04-14 23:52:17,537 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,537 INFO > Global (OK=2504 KO=0 ) 2024-04-14 23:52:17,537 INFO > userRestLogin (OK=2504 KO=0 ) 2024-04-14 23:52:17,537 INFO 2024-04-14 23:52:17,537 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,537 INFO [- ] 0% 2024-04-14 23:52:17,537 INFO waiting: 21690075 / active: 13 / done: 2504 2024-04-14 23:52:17,537 INFO ================================================================================ 2024-04-14 23:52:17,537 INFO 2024-04-14 23:52:17,537 INFO 2024-04-14 23:52:17,537 INFO ================================================================================ 2024-04-14 23:52:17,537 INFO 2024-04-14 23:49:54 10s elapsed 2024-04-14 23:52:17,537 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,537 INFO > Global (OK=10000 KO=0 ) 2024-04-14 23:52:17,537 INFO > userRestLogin (OK=10000 KO=0 ) 2024-04-14 23:52:17,537 INFO 2024-04-14 23:52:17,537 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,537 INFO [- ] 0% 2024-04-14 23:52:17,537 INFO waiting: 21682562 / active: 30 / done: 10000 2024-04-14 23:52:17,538 INFO ================================================================================ 2024-04-14 23:52:17,538 INFO 2024-04-14 23:52:17,538 INFO 2024-04-14 23:52:17,538 INFO ================================================================================ 2024-04-14 23:52:17,538 INFO 2024-04-14 23:49:59 15s elapsed 2024-04-14 23:52:17,538 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,538 INFO > Global (OK=22507 KO=0 ) 2024-04-14 23:52:17,538 INFO > userRestLogin (OK=22507 KO=0 ) 2024-04-14 23:52:17,538 INFO 2024-04-14 23:52:17,538 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,538 INFO [- ] 0% 2024-04-14 23:52:17,538 INFO waiting: 21670045 / active: 41 / done: 22506 2024-04-14 23:52:17,538 INFO ================================================================================ 2024-04-14 23:52:17,538 INFO 2024-04-14 23:52:17,538 INFO 2024-04-14 23:52:17,538 INFO ================================================================================ 2024-04-14 23:52:17,538 INFO 2024-04-14 23:50:04 20s elapsed 2024-04-14 23:52:17,538 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,538 INFO > Global (OK=40001 KO=0 ) 2024-04-14 23:52:17,538 INFO > userRestLogin (OK=40001 KO=0 ) 2024-04-14 23:52:17,538 INFO 2024-04-14 23:52:17,538 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,538 INFO [- ] 0% 2024-04-14 23:52:17,538 INFO waiting: 21652530 / active: 61 / done: 40001 2024-04-14 23:52:17,538 INFO ================================================================================ 2024-04-14 23:52:17,538 INFO 2024-04-14 23:52:17,538 INFO 2024-04-14 23:52:17,538 INFO ================================================================================ 2024-04-14 23:52:17,538 INFO 2024-04-14 23:50:09 25s elapsed 2024-04-14 23:52:17,538 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,538 INFO > Global (OK=62485 KO=0 ) 2024-04-14 23:52:17,538 INFO > userRestLogin (OK=62485 KO=0 ) 2024-04-14 23:52:17,538 INFO 2024-04-14 23:52:17,538 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,538 INFO [- ] 0% 2024-04-14 23:52:17,538 INFO waiting: 21630020 / active: 87 / done: 62485 2024-04-14 23:52:17,538 INFO ================================================================================ 2024-04-14 23:52:17,539 INFO 2024-04-14 23:52:17,539 INFO 2024-04-14 23:52:17,539 INFO ================================================================================ 2024-04-14 23:52:17,539 INFO 2024-04-14 23:50:14 30s elapsed 2024-04-14 23:52:17,539 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,539 INFO > Global (OK=89584 KO=0 ) 2024-04-14 23:52:17,539 INFO > userRestLogin (OK=89584 KO=0 ) 2024-04-14 23:52:17,539 INFO 2024-04-14 23:52:17,539 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,539 INFO [- ] 0% 2024-04-14 23:52:17,539 INFO waiting: 21602683 / active: 325 / done: 89584 2024-04-14 23:52:17,539 INFO ================================================================================ 2024-04-14 23:52:17,539 INFO 2024-04-14 23:52:17,539 INFO 2024-04-14 23:52:17,539 INFO ================================================================================ 2024-04-14 23:52:17,539 INFO 2024-04-14 23:50:19 35s elapsed 2024-04-14 23:52:17,539 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,539 INFO > Global (OK=119964 KO=0 ) 2024-04-14 23:52:17,539 INFO > userRestLogin (OK=119964 KO=0 ) 2024-04-14 23:52:17,539 INFO 2024-04-14 23:52:17,539 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,539 INFO [- ] 0% 2024-04-14 23:52:17,539 INFO waiting: 21572489 / active: 140 / done: 119963 2024-04-14 23:52:17,539 INFO ================================================================================ 2024-04-14 23:52:17,539 INFO 2024-04-14 23:52:17,539 INFO 2024-04-14 23:52:17,539 INFO ================================================================================ 2024-04-14 23:52:17,539 INFO 2024-04-14 23:50:24 40s elapsed 2024-04-14 23:52:17,539 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,539 INFO > Global (OK=149569 KO=0 ) 2024-04-14 23:52:17,539 INFO > userRestLogin (OK=149569 KO=0 ) 2024-04-14 23:52:17,539 INFO 2024-04-14 23:52:17,539 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,539 INFO [- ] 0% 2024-04-14 23:52:17,539 INFO waiting: 21542883 / active: 142 / done: 149567 2024-04-14 23:52:17,539 INFO ================================================================================ 2024-04-14 23:52:17,539 INFO 2024-04-14 23:52:17,539 INFO 2024-04-14 23:52:17,539 INFO [...] 2024-04-14 23:52:17,542 INFO 2024-04-14 23:52:17,543 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,543 INFO [#########################################################################-] 99% 2024-04-14 23:52:17,543 INFO waiting: 119583 / active: 134 / done: 21572875 2024-04-14 23:52:17,543 INFO ================================================================================ 2024-04-14 23:52:17,543 INFO 2024-04-14 23:52:17,543 INFO 2024-04-14 23:52:17,543 INFO ================================================================================ 2024-04-14 23:52:17,543 INFO 2024-04-15 00:49:59 3615s elapsed 2024-04-14 23:52:17,543 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,543 INFO > Global (OK=21602569 KO=1 ) 2024-04-14 23:52:17,543 INFO > userRestLogin (OK=21602569 KO=1 ) 2024-04-14 23:52:17,543 INFO ---- Errors -------------------------------------------------------------------- 2024-04-14 23:52:17,543 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-14 23:52:17,543 INFO ), but actually found 502 2024-04-14 23:52:17,543 INFO 2024-04-14 23:52:17,543 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,543 INFO [#########################################################################-] 99% 2024-04-14 23:52:17,543 INFO waiting: 89899 / active: 123 / done: 21602570 2024-04-14 23:52:17,543 INFO ================================================================================ 2024-04-14 23:52:17,543 INFO 2024-04-14 23:52:17,543 INFO 2024-04-14 23:52:17,543 INFO ================================================================================ 2024-04-14 23:52:17,543 INFO 2024-04-15 00:50:04 3620s elapsed 2024-04-14 23:52:17,543 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,543 INFO > Global (OK=21632575 KO=1 ) 2024-04-14 23:52:17,543 INFO > userRestLogin (OK=21632575 KO=1 ) 2024-04-14 23:52:17,543 INFO ---- Errors -------------------------------------------------------------------- 2024-04-14 23:52:17,543 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-14 23:52:17,543 INFO ), but actually found 502 2024-04-14 23:52:17,543 INFO 2024-04-14 23:52:17,543 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,543 INFO [#########################################################################-] 99% 2024-04-14 23:52:17,543 INFO waiting: 59889 / active: 129 / done: 21632574 2024-04-14 23:52:17,543 INFO ================================================================================ 2024-04-14 23:52:17,543 INFO 2024-04-14 23:52:17,543 INFO 2024-04-14 23:52:17,543 INFO ================================================================================ 2024-04-14 23:52:17,544 INFO 2024-04-15 00:50:09 3625s elapsed 2024-04-14 23:52:17,544 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,544 INFO > Global (OK=21662691 KO=1 ) 2024-04-14 23:52:17,544 INFO > userRestLogin (OK=21662691 KO=1 ) 2024-04-14 23:52:17,544 INFO ---- Errors -------------------------------------------------------------------- 2024-04-14 23:52:17,544 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-14 23:52:17,544 INFO ), but actually found 502 2024-04-14 23:52:17,544 INFO 2024-04-14 23:52:17,544 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,544 INFO [#########################################################################-] 99% 2024-04-14 23:52:17,544 INFO waiting: 29764 / active: 136 / done: 21662692 2024-04-14 23:52:17,544 INFO ================================================================================ 2024-04-14 23:52:17,544 INFO 2024-04-14 23:52:17,544 INFO 2024-04-14 23:52:17,544 INFO ================================================================================ 2024-04-14 23:52:17,544 INFO 2024-04-15 00:50:14 3630s elapsed 2024-04-14 23:52:17,544 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,544 INFO > Global (OK=21692345 KO=1 ) 2024-04-14 23:52:17,544 INFO > userRestLogin (OK=21692345 KO=1 ) 2024-04-14 23:52:17,544 INFO ---- Errors -------------------------------------------------------------------- 2024-04-14 23:52:17,544 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-14 23:52:17,544 INFO ), but actually found 502 2024-04-14 23:52:17,544 INFO 2024-04-14 23:52:17,544 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,544 INFO [#########################################################################-] 99% 2024-04-14 23:52:17,544 INFO waiting: 0 / active: 246 / done: 21692346 2024-04-14 23:52:17,544 INFO ================================================================================ 2024-04-14 23:52:17,544 INFO 2024-04-14 23:52:17,544 INFO 2024-04-14 23:52:17,544 INFO ================================================================================ 2024-04-14 23:52:17,544 INFO 2024-04-15 00:50:14 3630s elapsed 2024-04-14 23:52:17,544 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 23:52:17,544 INFO > Global (OK=21692591 KO=1 ) 2024-04-14 23:52:17,544 INFO > userRestLogin (OK=21692591 KO=1 ) 2024-04-14 23:52:17,544 INFO ---- Errors -------------------------------------------------------------------- 2024-04-14 23:52:17,544 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 1 (100.0%) 2024-04-14 23:52:17,544 INFO ), but actually found 502 2024-04-14 23:52:17,544 INFO 2024-04-14 23:52:17,545 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 23:52:17,545 INFO [##########################################################################]100% 2024-04-14 23:52:17,545 INFO waiting: 0 / active: 0 / done: 21692592 2024-04-14 23:52:17,545 INFO ================================================================================ 2024-04-14 23:52:17,545 INFO 2024-04-14 23:52:17,545 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-14 23:52:17,545 INFO 2024-04-14 23:52:17,545 INFO ---------------------- 2024-04-14 23:52:17,545 INFO [15/Apr/2024 00:50:14] Create tar file authn-users-6000.tar.gz 2024-04-14 23:52:17,545 INFO ---------------------- 2024-04-14 23:52:17,545 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-14 23:52:17,545 INFO RESULT_FOLDER=authn-users-6000 2024-04-14 23:52:17,545 INFO SIMULATION_PATH=authn-users-6000/loadtest-20240414224943666 2024-04-14 23:52:17,545 INFO PWD=/results/gatling/authn-users-6000/loadtest-20240414224943666 2024-04-14 23:52:17,545 INFO [cmd] tar -czvf /results/gatling//authn-users-6000.tar.gz --exclude=simulation.log * 2024-04-14 23:52:17,545 INFO /results/gatling 2024-04-14 23:52:17,545 INFO 2024-04-14 23:52:17,545 INFO ---------------------- 2024-04-14 23:52:17,545 INFO [15/Apr/2024 00:50:14] Update /results/gatling//latest 2024-04-14 23:52:17,545 INFO ---------------------- 2024-04-14 23:52:17,545 INFO 2024-04-14 23:52:17,545 INFO ---------------------- 2024-04-14 23:52:17,545 INFO [15/Apr/2024 00:50:14] Update /results/gatling//latest.tar.gz 2024-04-14 23:52:17,545 INFO ---------------------- 2024-04-14 23:52:17,545 INFO ________________________________________________________________________________ [2024-04-14 23:52:17] authn-users-6000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-14 23:52:17,546 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-6000/stderr.txt is small (0 lines), printing all lines: 2024-04-14 23:52:17,546 DEBUG ----- output ----- 2024-04-14 23:52:17,546 DEBUG 2024-04-14 23:52:17,546 INFO ________________________________________________________________________________ [2024-04-14 23:52:17] authn-users-6000 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-14 23:52:17] authn-users-6000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-14 23:52:17] authn-users-6000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped