--Task-- name: authn-users-5000 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['authn-users-3000'] preceding_task: authn-users-3000 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 5000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-14 20:44:02] authn-users-5000 pre : N/A ________________________________________________________________________________ [2024-04-14 20:44:02] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-14 20:44:02] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-5000/stdout.txt Pod log : /results/orders/authn-users-5000 2024-04-14 20:44:02,811 INFO 2024-04-14 20:44:02,811 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 20:44:02,811 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:44:03,173 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:44:03,173 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:44:03,173 DEBUG --- stdout --- 2024-04-14 20:44:03,173 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:44:03,173 DEBUG --- stderr --- 2024-04-14 20:44:03,173 DEBUG ________________________________________________________________________________ [2024-04-14 20:44:03] authn-users-5000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-14 20:44:03,174 INFO 2024-04-14 20:44:03,174 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 20:44:03,174 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:44:03,501 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:44:03,501 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:44:03,501 DEBUG --- stdout --- 2024-04-14 20:44:03,501 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:44:03,501 DEBUG --- stderr --- 2024-04-14 20:44:03,501 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-5000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-14 20:44:03] authn-users-5000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-14 20:44:03,502 INFO 2024-04-14 20:44:03,502 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 20:44:03,502 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:44:03,835 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:44:03,836 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:44:03,836 DEBUG --- stdout --- 2024-04-14 20:44:03,836 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:44:03,837 DEBUG --- stderr --- 2024-04-14 20:44:03,837 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/order.json { "name": "authn-users-5000", "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-5000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-5000", "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=5000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-14 20:44:03] authn-users-5000 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 20:44:03,838 INFO 2024-04-14 20:44:03,839 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-14 20:44:03,839 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 20:44:04,081 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 20:44:04,081 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:44:04,081 DEBUG --- stdout --- 2024-04-14 20:44:04,081 DEBUG WAITING 2024-04-14 20:44:04,081 DEBUG --- stderr --- 2024-04-14 20:44:04,081 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-14 20:44:04] authn-users-5000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-14 20:44:04,082 INFO 2024-04-14 20:44:04,082 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 20:44:04,082 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:44:04,465 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:44:04,466 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:44:04,466 DEBUG --- stdout --- 2024-04-14 20:44:04,466 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:44:04,466 DEBUG --- stderr --- 2024-04-14 20:44:04,466 DEBUG 2024-04-14 20:44:04,466 INFO 2024-04-14 20:44:04,466 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/04_authn-users-5000/order.json overseer-0-5584f6c4db-bcn5q:/results/orders/order.json 2024-04-14 20:44:04,466 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 20:44:05,923 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:44:05,923 DEBUG --- stdout --- 2024-04-14 20:44:05,923 DEBUG 2024-04-14 20:44:05,923 DEBUG --- stderr --- 2024-04-14 20:44:05,924 DEBUG Order has been successfully forwarded to overseer [2024-04-14 20:44:05] - INFO: Check if Gatling simulation started successfully 2024-04-14 20:44:05,924 INFO 2024-04-14 20:44:05,924 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 20:44:05,924 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 20:44:06,247 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 20:44:06,247 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:44:06,247 DEBUG --- stdout --- 2024-04-14 20:44:06,247 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 20:44:06,247 DEBUG --- stderr --- 2024-04-14 20:44:06,247 DEBUG 2024-04-14 20:44:06,248 INFO 2024-04-14 20:44:06,248 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-5000/stdout.txt | grep "started..." 2024-04-14 20:44:06,248 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 20:44:07,015 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-14 20:44:12,793 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-14 20:44:18,569 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-14 20:44:18,570 INFO [loop_until]: OK (rc = 0) 2024-04-14 20:44:18,570 DEBUG --- stdout --- 2024-04-14 20:44:18,570 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 20:44:18,570 DEBUG --- stderr --- 2024-04-14 20:44:18,570 DEBUG ________________________________________________________________________________ [2024-04-14 20:44:18] authn-users-5000 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 21:46:29] authn-users-5000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-14 21:46:29,205 INFO 2024-04-14 21:46:29,206 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 21:46:29,206 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:29,606 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:29,606 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:29,606 DEBUG --- stdout --- 2024-04-14 21:46:29,606 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:29,606 DEBUG --- stderr --- 2024-04-14 21:46:29,606 DEBUG 2024-04-14 21:46:29,607 INFO 2024-04-14 21:46:29,607 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 21:46:29,607 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 21:46:30,426 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:30,426 DEBUG --- stdout --- 2024-04-14 21:46:30,426 DEBUG 2024-04-14 21:46:30,427 DEBUG --- stderr --- 2024-04-14 21:46:30,427 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-14 21:46:30] authn-users-5000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-14 21:46:30] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/order.json 2024-04-14 21:46:30,427 INFO 2024-04-14 21:46:30,427 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 21:46:30,427 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:30,757 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:30,757 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:30,757 DEBUG --- stdout --- 2024-04-14 21:46:30,757 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:30,757 DEBUG --- stderr --- 2024-04-14 21:46:30,757 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 21:46:30,758 INFO 2024-04-14 21:46:30,758 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 21:46:30,758 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:31,088 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:31,089 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:31,089 DEBUG --- stdout --- 2024-04-14 21:46:31,089 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:31,089 DEBUG --- stderr --- 2024-04-14 21:46:31,089 DEBUG 2024-04-14 21:46:31,090 INFO 2024-04-14 21:46:31,090 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-5000/rc.txt" ]; then echo "/results/orders/authn-users-5000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-5000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5000/rc.txt file found" 2024-04-14 21:46:31,090 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 21:46:31,935 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 21:46:31,935 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:31,935 DEBUG --- stdout --- 2024-04-14 21:46:31,935 DEBUG /results/orders/authn-users-5000/rc.txt file found 2024-04-14 21:46:31,935 DEBUG --- stderr --- 2024-04-14 21:46:31,936 DEBUG 2024-04-14 21:46:31,936 INFO 2024-04-14 21:46:31,936 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 21:46:31,936 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:32,337 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:32,337 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:32,338 DEBUG --- stdout --- 2024-04-14 21:46:32,338 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:32,338 DEBUG --- stderr --- 2024-04-14 21:46:32,338 DEBUG 2024-04-14 21:46:32,338 INFO 2024-04-14 21:46:32,338 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-5000/rc.txt 2024-04-14 21:46:33,164 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 21:46:33,165 DEBUG --- stdout --- 2024-04-14 21:46:33,165 DEBUG 4 /results/orders/authn-users-5000/rc.txt 2024-04-14 21:46:33,165 DEBUG --- stderr --- 2024-04-14 21:46:33,165 DEBUG 2024-04-14 21:46:33,165 INFO - Download file (size 4k) 2024-04-14 21:46:33,165 INFO 2024-04-14 21:46:33,165 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 21:46:33,165 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:33,470 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:33,471 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:33,471 DEBUG --- stdout --- 2024-04-14 21:46:33,471 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:33,471 DEBUG --- stderr --- 2024-04-14 21:46:33,471 DEBUG source : /results/orders/authn-users-5000/rc.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/rc.txt 2024-04-14 21:46:33,471 INFO 2024-04-14 21:46:33,471 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 21:46:33,471 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:33,776 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:33,777 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:33,777 DEBUG --- stdout --- 2024-04-14 21:46:33,777 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:33,777 DEBUG --- stderr --- 2024-04-14 21:46:33,777 DEBUG 2024-04-14 21:46:33,777 INFO 2024-04-14 21:46:33,777 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-5000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/rc.txt 2024-04-14 21:46:33,777 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 21:46:34,527 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:34,527 DEBUG --- stdout --- 2024-04-14 21:46:34,527 DEBUG tar: Removing leading `/' from member names 2024-04-14 21:46:34,527 DEBUG --- stderr --- 2024-04-14 21:46:34,527 DEBUG 2024-04-14 21:46:34,528 INFO 2024-04-14 21:46:34,528 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 21:46:34,528 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:34,833 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:34,833 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:34,833 DEBUG --- stdout --- 2024-04-14 21:46:34,833 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:34,833 DEBUG --- stderr --- 2024-04-14 21:46:34,833 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 21:46:34,834 INFO 2024-04-14 21:46:34,834 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 21:46:34,834 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:35,147 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:35,147 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:35,148 DEBUG --- stdout --- 2024-04-14 21:46:35,148 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:35,148 DEBUG --- stderr --- 2024-04-14 21:46:35,148 DEBUG 2024-04-14 21:46:35,148 INFO 2024-04-14 21:46:35,148 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-5000/stderr.txt" ]; then echo "/results/orders/authn-users-5000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-5000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5000/stderr.txt file found" 2024-04-14 21:46:35,148 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 21:46:35,990 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 21:46:35,990 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:35,990 DEBUG --- stdout --- 2024-04-14 21:46:35,991 DEBUG /results/orders/authn-users-5000/stderr.txt file found 2024-04-14 21:46:35,991 DEBUG --- stderr --- 2024-04-14 21:46:35,991 DEBUG 2024-04-14 21:46:35,991 INFO 2024-04-14 21:46:35,991 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 21:46:35,991 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:36,327 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:36,327 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:36,327 DEBUG --- stdout --- 2024-04-14 21:46:36,327 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:36,327 DEBUG --- stderr --- 2024-04-14 21:46:36,327 DEBUG 2024-04-14 21:46:36,327 INFO 2024-04-14 21:46:36,328 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-5000/stderr.txt 2024-04-14 21:46:37,191 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 21:46:37,193 DEBUG --- stdout --- 2024-04-14 21:46:37,193 DEBUG 0 /results/orders/authn-users-5000/stderr.txt 2024-04-14 21:46:37,193 DEBUG --- stderr --- 2024-04-14 21:46:37,193 DEBUG 2024-04-14 21:46:37,193 INFO - Download file (size 0k) 2024-04-14 21:46:37,194 INFO 2024-04-14 21:46:37,194 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 21:46:37,194 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:37,539 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:37,540 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:37,540 DEBUG --- stdout --- 2024-04-14 21:46:37,540 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:37,540 DEBUG --- stderr --- 2024-04-14 21:46:37,540 DEBUG source : /results/orders/authn-users-5000/stderr.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/stderr.txt 2024-04-14 21:46:37,540 INFO 2024-04-14 21:46:37,540 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 21:46:37,540 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:37,882 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:37,882 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:37,882 DEBUG --- stdout --- 2024-04-14 21:46:37,882 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:37,882 DEBUG --- stderr --- 2024-04-14 21:46:37,882 DEBUG 2024-04-14 21:46:37,883 INFO 2024-04-14 21:46:37,883 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-5000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/stderr.txt 2024-04-14 21:46:37,883 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 21:46:38,659 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:38,659 DEBUG --- stdout --- 2024-04-14 21:46:38,660 DEBUG tar: Removing leading `/' from member names 2024-04-14 21:46:38,660 DEBUG --- stderr --- 2024-04-14 21:46:38,660 DEBUG 2024-04-14 21:46:38,660 INFO 2024-04-14 21:46:38,660 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 21:46:38,660 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:38,993 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:38,993 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:38,993 DEBUG --- stdout --- 2024-04-14 21:46:38,993 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:38,993 DEBUG --- stderr --- 2024-04-14 21:46:38,993 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 21:46:38,993 INFO 2024-04-14 21:46:38,994 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 21:46:38,994 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:39,319 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:39,319 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:39,319 DEBUG --- stdout --- 2024-04-14 21:46:39,319 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:39,319 DEBUG --- stderr --- 2024-04-14 21:46:39,319 DEBUG 2024-04-14 21:46:39,320 INFO 2024-04-14 21:46:39,320 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-5000/stdout.txt" ]; then echo "/results/orders/authn-users-5000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-5000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5000/stdout.txt file found" 2024-04-14 21:46:39,320 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 21:46:40,171 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 21:46:40,171 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:40,171 DEBUG --- stdout --- 2024-04-14 21:46:40,171 DEBUG /results/orders/authn-users-5000/stdout.txt file found 2024-04-14 21:46:40,171 DEBUG --- stderr --- 2024-04-14 21:46:40,171 DEBUG 2024-04-14 21:46:40,171 INFO 2024-04-14 21:46:40,171 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 21:46:40,171 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:40,481 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:40,482 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:40,482 DEBUG --- stdout --- 2024-04-14 21:46:40,482 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:40,482 DEBUG --- stderr --- 2024-04-14 21:46:40,482 DEBUG 2024-04-14 21:46:40,482 INFO 2024-04-14 21:46:40,482 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-5000/stdout.txt 2024-04-14 21:46:41,308 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 21:46:41,309 DEBUG --- stdout --- 2024-04-14 21:46:41,309 DEBUG 636 /results/orders/authn-users-5000/stdout.txt 2024-04-14 21:46:41,310 DEBUG --- stderr --- 2024-04-14 21:46:41,310 DEBUG 2024-04-14 21:46:41,310 INFO - Download file (size 636k) 2024-04-14 21:46:41,311 INFO 2024-04-14 21:46:41,311 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 21:46:41,311 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:41,660 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:41,660 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:41,660 DEBUG --- stdout --- 2024-04-14 21:46:41,660 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:41,660 DEBUG --- stderr --- 2024-04-14 21:46:41,660 DEBUG source : /results/orders/authn-users-5000/stdout.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/stdout.txt 2024-04-14 21:46:41,660 INFO 2024-04-14 21:46:41,660 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 21:46:41,660 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:42,005 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:42,005 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:42,005 DEBUG --- stdout --- 2024-04-14 21:46:42,005 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:42,005 DEBUG --- stderr --- 2024-04-14 21:46:42,005 DEBUG 2024-04-14 21:46:42,005 INFO 2024-04-14 21:46:42,006 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-5000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/stdout.txt 2024-04-14 21:46:42,006 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 21:46:43,085 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:43,085 DEBUG --- stdout --- 2024-04-14 21:46:43,085 DEBUG tar: Removing leading `/' from member names 2024-04-14 21:46:43,085 DEBUG --- stderr --- 2024-04-14 21:46:43,085 DEBUG ________________________________________________________________________________ [2024-04-14 21:46:43] authn-users-5000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-14 21:46:43,086 INFO 2024-04-14 21:46:43,086 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 21:46:43,086 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:43,411 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:43,411 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:43,411 DEBUG --- stdout --- 2024-04-14 21:46:43,411 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:43,411 DEBUG --- stderr --- 2024-04-14 21:46:43,411 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 21:46:43,411 INFO 2024-04-14 21:46:43,411 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 21:46:43,412 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:43,735 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:43,735 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:43,735 DEBUG --- stdout --- 2024-04-14 21:46:43,735 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:43,735 DEBUG --- stderr --- 2024-04-14 21:46:43,735 DEBUG 2024-04-14 21:46:43,736 INFO 2024-04-14 21:46:43,736 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-5000/cmd.txt" ]; then echo "/results/orders/authn-users-5000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-5000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-5000/cmd.txt file found" 2024-04-14 21:46:43,736 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 21:46:44,609 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 21:46:44,609 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:44,609 DEBUG --- stdout --- 2024-04-14 21:46:44,609 DEBUG /results/orders/authn-users-5000/cmd.txt file found 2024-04-14 21:46:44,609 DEBUG --- stderr --- 2024-04-14 21:46:44,609 DEBUG 2024-04-14 21:46:44,610 INFO 2024-04-14 21:46:44,610 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 21:46:44,610 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:44,916 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:44,917 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:44,917 DEBUG --- stdout --- 2024-04-14 21:46:44,917 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:44,917 DEBUG --- stderr --- 2024-04-14 21:46:44,917 DEBUG 2024-04-14 21:46:44,917 INFO 2024-04-14 21:46:44,917 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-5000/cmd.txt 2024-04-14 21:46:45,752 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 21:46:45,752 DEBUG --- stdout --- 2024-04-14 21:46:45,752 DEBUG 4 /results/orders/authn-users-5000/cmd.txt 2024-04-14 21:46:45,752 DEBUG --- stderr --- 2024-04-14 21:46:45,752 DEBUG 2024-04-14 21:46:45,752 INFO - Download file (size 4k) 2024-04-14 21:46:45,753 INFO 2024-04-14 21:46:45,753 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 21:46:45,753 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:46,062 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:46,063 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:46,063 DEBUG --- stdout --- 2024-04-14 21:46:46,063 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:46,063 DEBUG --- stderr --- 2024-04-14 21:46:46,063 DEBUG source : /results/orders/authn-users-5000/cmd.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/cmd.txt 2024-04-14 21:46:46,063 INFO 2024-04-14 21:46:46,063 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 21:46:46,063 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 21:46:46,368 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 21:46:46,369 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:46,369 DEBUG --- stdout --- 2024-04-14 21:46:46,369 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 21:46:46,369 DEBUG --- stderr --- 2024-04-14 21:46:46,369 DEBUG 2024-04-14 21:46:46,369 INFO 2024-04-14 21:46:46,369 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-5000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/cmd.txt 2024-04-14 21:46:46,369 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 21:46:47,110 INFO [loop_until]: OK (rc = 0) 2024-04-14 21:46:47,110 DEBUG --- stdout --- 2024-04-14 21:46:47,110 DEBUG tar: Removing leading `/' from member names 2024-04-14 21:46:47,110 DEBUG --- stderr --- 2024-04-14 21:46:47,110 DEBUG 2024-04-14 21:46:47,111 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/cmd.txt is small (1 lines), printing all lines: 2024-04-14 21:46:47,111 DEBUG ----- output ----- 2024-04-14 21:46:47,111 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-5000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-14 21:46:47,111 INFO ________________________________________________________________________________ [2024-04-14 21:46:47] authn-users-5000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-14 21:46:47,111 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/rc.txt is small (1 lines), printing all lines: 2024-04-14 21:46:47,111 DEBUG ----- output ----- 2024-04-14 21:46:47,111 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-14 21:46:47,111 INFO ________________________________________________________________________________ [2024-04-14 21:46:47] authn-users-5000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-14 21:46:47,113 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/04_authn-users-5000/stdout.txt is 10827 lines): 2024-04-14 21:46:47,113 DEBUG ----- output ----- 2024-04-14 21:46:47,114 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-14 21:46:47,114 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-14 21:46:47,114 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-14 21:46:47,114 INFO 2024-04-14 21:46:47,114 INFO ================================================================================ 2024-04-14 21:46:47,114 INFO 2024-04-14 21:44:18 5s elapsed 2024-04-14 21:46:47,114 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,114 INFO > Global (OK=2082 KO=0 ) 2024-04-14 21:46:47,114 INFO > userRestLogin (OK=2082 KO=0 ) 2024-04-14 21:46:47,114 INFO 2024-04-14 21:46:47,114 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,114 INFO [- ] 0% 2024-04-14 21:46:47,114 INFO waiting: 18075177 / active: 9 / done: 2082 2024-04-14 21:46:47,114 INFO ================================================================================ 2024-04-14 21:46:47,114 INFO 2024-04-14 21:46:47,114 INFO 2024-04-14 21:46:47,114 INFO ================================================================================ 2024-04-14 21:46:47,114 INFO 2024-04-14 21:44:23 10s elapsed 2024-04-14 21:46:47,114 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,114 INFO > Global (OK=8328 KO=0 ) 2024-04-14 21:46:47,114 INFO > userRestLogin (OK=8328 KO=0 ) 2024-04-14 21:46:47,114 INFO 2024-04-14 21:46:47,114 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,114 INFO [- ] 0% 2024-04-14 21:46:47,114 INFO waiting: 18068921 / active: 19 / done: 8328 2024-04-14 21:46:47,114 INFO ================================================================================ 2024-04-14 21:46:47,114 INFO 2024-04-14 21:46:47,114 INFO 2024-04-14 21:46:47,114 INFO ================================================================================ 2024-04-14 21:46:47,114 INFO 2024-04-14 21:44:28 15s elapsed 2024-04-14 21:46:47,114 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,114 INFO > Global (OK=18748 KO=0 ) 2024-04-14 21:46:47,114 INFO > userRestLogin (OK=18748 KO=0 ) 2024-04-14 21:46:47,114 INFO 2024-04-14 21:46:47,114 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,114 INFO [- ] 0% 2024-04-14 21:46:47,114 INFO waiting: 18058494 / active: 27 / done: 18747 2024-04-14 21:46:47,114 INFO ================================================================================ 2024-04-14 21:46:47,115 INFO 2024-04-14 21:46:47,115 INFO 2024-04-14 21:46:47,115 INFO ================================================================================ 2024-04-14 21:46:47,115 INFO 2024-04-14 21:44:33 20s elapsed 2024-04-14 21:46:47,115 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,115 INFO > Global (OK=33323 KO=0 ) 2024-04-14 21:46:47,115 INFO > userRestLogin (OK=33323 KO=0 ) 2024-04-14 21:46:47,115 INFO 2024-04-14 21:46:47,115 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,115 INFO [- ] 0% 2024-04-14 21:46:47,115 INFO waiting: 18043907 / active: 39 / done: 33322 2024-04-14 21:46:47,115 INFO ================================================================================ 2024-04-14 21:46:47,115 INFO 2024-04-14 21:46:47,115 INFO 2024-04-14 21:46:47,115 INFO ================================================================================ 2024-04-14 21:46:47,115 INFO 2024-04-14 21:44:38 25s elapsed 2024-04-14 21:46:47,115 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,115 INFO > Global (OK=52058 KO=0 ) 2024-04-14 21:46:47,115 INFO > userRestLogin (OK=52058 KO=0 ) 2024-04-14 21:46:47,115 INFO 2024-04-14 21:46:47,115 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,115 INFO [- ] 0% 2024-04-14 21:46:47,115 INFO waiting: 18025151 / active: 59 / done: 52058 2024-04-14 21:46:47,115 INFO ================================================================================ 2024-04-14 21:46:47,115 INFO 2024-04-14 21:46:47,115 INFO 2024-04-14 21:46:47,115 INFO ================================================================================ 2024-04-14 21:46:47,115 INFO 2024-04-14 21:44:43 30s elapsed 2024-04-14 21:46:47,115 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,115 INFO > Global (OK=74907 KO=0 ) 2024-04-14 21:46:47,115 INFO > userRestLogin (OK=74907 KO=0 ) 2024-04-14 21:46:47,115 INFO 2024-04-14 21:46:47,115 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,115 INFO [- ] 0% 2024-04-14 21:46:47,115 INFO waiting: 18002236 / active: 125 / done: 74907 2024-04-14 21:46:47,115 INFO ================================================================================ 2024-04-14 21:46:47,115 INFO 2024-04-14 21:46:47,115 INFO 2024-04-14 21:46:47,115 INFO ================================================================================ 2024-04-14 21:46:47,115 INFO 2024-04-14 21:44:48 35s elapsed 2024-04-14 21:46:47,115 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,115 INFO > Global (OK=99743 KO=0 ) 2024-04-14 21:46:47,116 INFO > userRestLogin (OK=99743 KO=0 ) 2024-04-14 21:46:47,116 INFO 2024-04-14 21:46:47,116 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,116 INFO [- ] 0% 2024-04-14 21:46:47,116 INFO waiting: 17977464 / active: 62 / done: 99742 2024-04-14 21:46:47,116 INFO ================================================================================ 2024-04-14 21:46:47,116 INFO 2024-04-14 21:46:47,116 INFO 2024-04-14 21:46:47,116 INFO ================================================================================ 2024-04-14 21:46:47,116 INFO 2024-04-14 21:44:53 40s elapsed 2024-04-14 21:46:47,116 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,116 INFO > Global (OK=124399 KO=0 ) 2024-04-14 21:46:47,116 INFO > userRestLogin (OK=124399 KO=0 ) 2024-04-14 21:46:47,116 INFO 2024-04-14 21:46:47,116 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,116 INFO [- ] 0% 2024-04-14 21:46:47,116 INFO waiting: 17952654 / active: 215 / done: 124399 2024-04-14 21:46:47,116 INFO ================================================================================ 2024-04-14 21:46:47,116 INFO 2024-04-14 21:46:47,116 INFO 2024-04-14 21:46:47,116 INFO [...] 2024-04-14 21:46:47,119 INFO 2024-04-14 21:46:47,119 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,120 INFO [#########################################################################-] 99% 2024-04-14 21:46:47,120 INFO waiting: 100217 / active: 77 / done: 17976974 2024-04-14 21:46:47,120 INFO ================================================================================ 2024-04-14 21:46:47,120 INFO 2024-04-14 21:46:47,120 INFO 2024-04-14 21:46:47,120 INFO ================================================================================ 2024-04-14 21:46:47,120 INFO 2024-04-14 22:44:28 3615s elapsed 2024-04-14 21:46:47,120 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,120 INFO > Global (OK=18002079 KO=4 ) 2024-04-14 21:46:47,120 INFO > userRestLogin (OK=18002079 KO=4 ) 2024-04-14 21:46:47,120 INFO ---- Errors -------------------------------------------------------------------- 2024-04-14 21:46:47,120 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 4 (100.0%) 2024-04-14 21:46:47,120 INFO ), but actually found 502 2024-04-14 21:46:47,120 INFO 2024-04-14 21:46:47,120 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,120 INFO [#########################################################################-] 99% 2024-04-14 21:46:47,120 INFO waiting: 75088 / active: 97 / done: 18002083 2024-04-14 21:46:47,120 INFO ================================================================================ 2024-04-14 21:46:47,120 INFO 2024-04-14 21:46:47,120 INFO 2024-04-14 21:46:47,120 INFO ================================================================================ 2024-04-14 21:46:47,120 INFO 2024-04-14 22:44:33 3620s elapsed 2024-04-14 21:46:47,120 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,120 INFO > Global (OK=18027069 KO=4 ) 2024-04-14 21:46:47,120 INFO > userRestLogin (OK=18027069 KO=4 ) 2024-04-14 21:46:47,120 INFO ---- Errors -------------------------------------------------------------------- 2024-04-14 21:46:47,120 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 4 (100.0%) 2024-04-14 21:46:47,120 INFO ), but actually found 502 2024-04-14 21:46:47,120 INFO 2024-04-14 21:46:47,120 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,120 INFO [#########################################################################-] 99% 2024-04-14 21:46:47,120 INFO waiting: 50136 / active: 60 / done: 18027072 2024-04-14 21:46:47,120 INFO ================================================================================ 2024-04-14 21:46:47,120 INFO 2024-04-14 21:46:47,120 INFO 2024-04-14 21:46:47,120 INFO ================================================================================ 2024-04-14 21:46:47,120 INFO 2024-04-14 22:44:38 3625s elapsed 2024-04-14 21:46:47,120 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,120 INFO > Global (OK=18052049 KO=4 ) 2024-04-14 21:46:47,120 INFO > userRestLogin (OK=18052049 KO=4 ) 2024-04-14 21:46:47,121 INFO ---- Errors -------------------------------------------------------------------- 2024-04-14 21:46:47,121 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 4 (100.0%) 2024-04-14 21:46:47,121 INFO ), but actually found 502 2024-04-14 21:46:47,121 INFO 2024-04-14 21:46:47,121 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,121 INFO [#########################################################################-] 99% 2024-04-14 21:46:47,121 INFO waiting: 25144 / active: 72 / done: 18052052 2024-04-14 21:46:47,121 INFO ================================================================================ 2024-04-14 21:46:47,121 INFO 2024-04-14 21:46:47,121 INFO 2024-04-14 21:46:47,121 INFO ================================================================================ 2024-04-14 21:46:47,121 INFO 2024-04-14 22:44:43 3630s elapsed 2024-04-14 21:46:47,121 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,121 INFO > Global (OK=18077228 KO=4 ) 2024-04-14 21:46:47,121 INFO > userRestLogin (OK=18077228 KO=4 ) 2024-04-14 21:46:47,121 INFO ---- Errors -------------------------------------------------------------------- 2024-04-14 21:46:47,121 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 4 (100.0%) 2024-04-14 21:46:47,121 INFO ), but actually found 502 2024-04-14 21:46:47,121 INFO 2024-04-14 21:46:47,121 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,121 INFO [#########################################################################-] 99% 2024-04-14 21:46:47,121 INFO waiting: 0 / active: 36 / done: 18077232 2024-04-14 21:46:47,121 INFO ================================================================================ 2024-04-14 21:46:47,121 INFO 2024-04-14 21:46:47,121 INFO 2024-04-14 21:46:47,121 INFO ================================================================================ 2024-04-14 21:46:47,121 INFO 2024-04-14 22:44:43 3630s elapsed 2024-04-14 21:46:47,121 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 21:46:47,121 INFO > Global (OK=18077264 KO=4 ) 2024-04-14 21:46:47,121 INFO > userRestLogin (OK=18077264 KO=4 ) 2024-04-14 21:46:47,121 INFO ---- Errors -------------------------------------------------------------------- 2024-04-14 21:46:47,121 INFO > status.find.in(200,201,202,203,204,205,206,207,208,209,304,401 4 (100.0%) 2024-04-14 21:46:47,121 INFO ), but actually found 502 2024-04-14 21:46:47,121 INFO 2024-04-14 21:46:47,121 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 21:46:47,121 INFO [##########################################################################]100% 2024-04-14 21:46:47,121 INFO waiting: 0 / active: 0 / done: 18077268 2024-04-14 21:46:47,121 INFO ================================================================================ 2024-04-14 21:46:47,121 INFO 2024-04-14 21:46:47,121 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-14 21:46:47,121 INFO 2024-04-14 21:46:47,121 INFO ---------------------- 2024-04-14 21:46:47,122 INFO [14/Apr/2024 22:44:43] Create tar file authn-users-5000.tar.gz 2024-04-14 21:46:47,122 INFO ---------------------- 2024-04-14 21:46:47,122 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-14 21:46:47,122 INFO RESULT_FOLDER=authn-users-5000 2024-04-14 21:46:47,122 INFO SIMULATION_PATH=authn-users-5000/loadtest-20240414204413012 2024-04-14 21:46:47,122 INFO PWD=/results/gatling/authn-users-5000/loadtest-20240414204413012 2024-04-14 21:46:47,122 INFO [cmd] tar -czvf /results/gatling//authn-users-5000.tar.gz --exclude=simulation.log * 2024-04-14 21:46:47,122 INFO /results/gatling 2024-04-14 21:46:47,122 INFO 2024-04-14 21:46:47,122 INFO ---------------------- 2024-04-14 21:46:47,122 INFO [14/Apr/2024 22:44:43] Update /results/gatling//latest 2024-04-14 21:46:47,122 INFO ---------------------- 2024-04-14 21:46:47,122 INFO 2024-04-14 21:46:47,122 INFO ---------------------- 2024-04-14 21:46:47,122 INFO [14/Apr/2024 22:44:43] Update /results/gatling//latest.tar.gz 2024-04-14 21:46:47,122 INFO ---------------------- 2024-04-14 21:46:47,122 INFO ________________________________________________________________________________ [2024-04-14 21:46:47] authn-users-5000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-14 21:46:47,122 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/04_authn-users-5000/stderr.txt is small (0 lines), printing all lines: 2024-04-14 21:46:47,122 DEBUG ----- output ----- 2024-04-14 21:46:47,122 DEBUG 2024-04-14 21:46:47,122 INFO ________________________________________________________________________________ [2024-04-14 21:46:47] authn-users-5000 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 21:46:47] authn-users-5000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-14 21:46:47] authn-users-5000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped