--Task-- name: authn-users-10000 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-9500'] preceding_task: authn-users-9500 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 10000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-22 03:27:18] authn-users-10000 pre : N/A ________________________________________________________________________________ [2024-04-22 03:27:18] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-22 03:27:18] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-10000/stdout.txt Pod log : /results/orders/authn-users-10000 2024-04-22 03:27:18,787 INFO 2024-04-22 03:27:18,787 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 03:27:18,787 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:19,102 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:19,103 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:19,103 DEBUG --- stdout --- 2024-04-22 03:27:19,103 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:19,103 DEBUG --- stderr --- 2024-04-22 03:27:19,103 DEBUG ________________________________________________________________________________ [2024-04-22 03:27:19] authn-users-10000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-22 03:27:19,104 INFO 2024-04-22 03:27:19,104 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 03:27:19,104 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:19,417 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:19,418 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:19,418 DEBUG --- stdout --- 2024-04-22 03:27:19,418 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:19,418 DEBUG --- stderr --- 2024-04-22 03:27:19,418 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-10000 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-22 03:27:19] authn-users-10000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-22 03:27:19,419 INFO 2024-04-22 03:27:19,419 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 03:27:19,419 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:19,732 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:19,733 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:19,733 DEBUG --- stdout --- 2024-04-22 03:27:19,733 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:19,733 DEBUG --- stderr --- 2024-04-22 03:27:19,733 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/order.json { "name": "authn-users-10000", "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-10000 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/authn-users-10000", "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=10000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-22 03:27:19] authn-users-10000 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-04-22 03:27:19,734 INFO 2024-04-22 03:27:19,734 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-22 03:27:19,735 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-22 03:27:19,973 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 03:27:19,973 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:19,973 DEBUG --- stdout --- 2024-04-22 03:27:19,973 DEBUG WAITING 2024-04-22 03:27:19,973 DEBUG --- stderr --- 2024-04-22 03:27:19,973 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-22 03:27:19] authn-users-10000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-22 03:27:19,974 INFO 2024-04-22 03:27:19,974 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 03:27:19,974 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:20,324 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:20,324 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:20,324 DEBUG --- stdout --- 2024-04-22 03:27:20,324 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:20,324 DEBUG --- stderr --- 2024-04-22 03:27:20,324 DEBUG 2024-04-22 03:27:20,325 INFO 2024-04-22 03:27:20,325 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-10000/order.json overseer-0-68c5d5b884-p97w5:/results/orders/order.json 2024-04-22 03:27:20,325 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 03:27:21,709 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:21,710 DEBUG --- stdout --- 2024-04-22 03:27:21,710 DEBUG 2024-04-22 03:27:21,710 DEBUG --- stderr --- 2024-04-22 03:27:21,710 DEBUG Order has been successfully forwarded to overseer [2024-04-22 03:27:21] - INFO: Check if Gatling simulation started successfully 2024-04-22 03:27:21,710 INFO 2024-04-22 03:27:21,710 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 03:27:21,710 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 03:27:22,027 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 03:27:22,027 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:22,027 DEBUG --- stdout --- 2024-04-22 03:27:22,027 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 03:27:22,027 DEBUG --- stderr --- 2024-04-22 03:27:22,027 DEBUG 2024-04-22 03:27:22,028 INFO 2024-04-22 03:27:22,028 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 -- head -n 25 /results/orders/authn-users-10000/stdout.txt | grep "started..." 2024-04-22 03:27:22,028 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-22 03:27:22,792 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-22 03:27:28,576 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-22 03:27:34,393 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-04-22 03:27:34,395 INFO [loop_until]: OK (rc = 0) 2024-04-22 03:27:34,395 DEBUG --- stdout --- 2024-04-22 03:27:34,395 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-04-22 03:27:34,395 DEBUG --- stderr --- 2024-04-22 03:27:34,395 DEBUG ________________________________________________________________________________ [2024-04-22 03:27:34] authn-users-10000 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 05s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 06s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 07s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 08s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 09s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 10s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-22 04:29:45] authn-users-10000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-22 04:29:45,302 INFO 2024-04-22 04:29:45,302 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:45,302 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:45,643 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:45,643 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:45,643 DEBUG --- stdout --- 2024-04-22 04:29:45,644 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:45,644 DEBUG --- stderr --- 2024-04-22 04:29:45,644 DEBUG 2024-04-22 04:29:45,644 INFO 2024-04-22 04:29:45,644 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 -- rm -f /results/orders/order.json 2024-04-22 04:29:45,644 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 04:29:46,451 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:46,452 DEBUG --- stdout --- 2024-04-22 04:29:46,452 DEBUG 2024-04-22 04:29:46,452 DEBUG --- stderr --- 2024-04-22 04:29:46,452 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-22 04:29:46] authn-users-10000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-22 04:29:46] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/order.json 2024-04-22 04:29:46,453 INFO 2024-04-22 04:29:46,453 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:46,453 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:46,767 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:46,767 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:46,767 DEBUG --- stdout --- 2024-04-22 04:29:46,767 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:46,767 DEBUG --- stderr --- 2024-04-22 04:29:46,767 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 04:29:46,768 INFO 2024-04-22 04:29:46,768 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:46,768 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:47,086 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:47,087 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:47,087 DEBUG --- stdout --- 2024-04-22 04:29:47,087 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:47,087 DEBUG --- stderr --- 2024-04-22 04:29:47,087 DEBUG 2024-04-22 04:29:47,088 INFO 2024-04-22 04:29:47,088 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-10000/rc.txt" ]; then echo "/results/orders/authn-users-10000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-10000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-10000/rc.txt file found" 2024-04-22 04:29:47,088 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 04:29:47,964 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 04:29:47,965 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:47,966 DEBUG --- stdout --- 2024-04-22 04:29:47,966 DEBUG /results/orders/authn-users-10000/rc.txt file found 2024-04-22 04:29:47,966 DEBUG --- stderr --- 2024-04-22 04:29:47,966 DEBUG 2024-04-22 04:29:47,966 INFO 2024-04-22 04:29:47,967 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:47,967 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:48,299 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:48,299 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:48,299 DEBUG --- stdout --- 2024-04-22 04:29:48,300 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:48,300 DEBUG --- stderr --- 2024-04-22 04:29:48,300 DEBUG 2024-04-22 04:29:48,300 INFO 2024-04-22 04:29:48,300 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-10000/rc.txt 2024-04-22 04:29:49,145 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 04:29:49,145 DEBUG --- stdout --- 2024-04-22 04:29:49,145 DEBUG 4 /results/orders/authn-users-10000/rc.txt 2024-04-22 04:29:49,145 DEBUG --- stderr --- 2024-04-22 04:29:49,145 DEBUG 2024-04-22 04:29:49,145 INFO - Download file (size 4k) 2024-04-22 04:29:49,146 INFO 2024-04-22 04:29:49,146 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:49,146 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:49,472 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:49,472 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:49,473 DEBUG --- stdout --- 2024-04-22 04:29:49,473 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:49,473 DEBUG --- stderr --- 2024-04-22 04:29:49,473 DEBUG source : /results/orders/authn-users-10000/rc.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/rc.txt 2024-04-22 04:29:49,473 INFO 2024-04-22 04:29:49,473 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:49,473 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:49,791 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:49,791 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:49,791 DEBUG --- stdout --- 2024-04-22 04:29:49,792 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:49,792 DEBUG --- stderr --- 2024-04-22 04:29:49,792 DEBUG 2024-04-22 04:29:49,792 INFO 2024-04-22 04:29:49,792 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-10000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/rc.txt 2024-04-22 04:29:49,792 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 04:29:50,548 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:50,548 DEBUG --- stdout --- 2024-04-22 04:29:50,548 DEBUG tar: Removing leading `/' from member names 2024-04-22 04:29:50,548 DEBUG --- stderr --- 2024-04-22 04:29:50,548 DEBUG 2024-04-22 04:29:50,549 INFO 2024-04-22 04:29:50,549 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:50,549 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:50,868 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:50,868 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:50,868 DEBUG --- stdout --- 2024-04-22 04:29:50,868 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:50,869 DEBUG --- stderr --- 2024-04-22 04:29:50,869 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 04:29:50,869 INFO 2024-04-22 04:29:50,869 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:50,869 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:51,185 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:51,185 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:51,185 DEBUG --- stdout --- 2024-04-22 04:29:51,186 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:51,186 DEBUG --- stderr --- 2024-04-22 04:29:51,186 DEBUG 2024-04-22 04:29:51,186 INFO 2024-04-22 04:29:51,186 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-10000/stderr.txt" ]; then echo "/results/orders/authn-users-10000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-10000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-10000/stderr.txt file found" 2024-04-22 04:29:51,187 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 04:29:52,027 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 04:29:52,028 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:52,028 DEBUG --- stdout --- 2024-04-22 04:29:52,028 DEBUG /results/orders/authn-users-10000/stderr.txt file found 2024-04-22 04:29:52,028 DEBUG --- stderr --- 2024-04-22 04:29:52,028 DEBUG 2024-04-22 04:29:52,028 INFO 2024-04-22 04:29:52,028 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:52,028 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:52,346 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:52,346 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:52,346 DEBUG --- stdout --- 2024-04-22 04:29:52,347 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:52,347 DEBUG --- stderr --- 2024-04-22 04:29:52,347 DEBUG 2024-04-22 04:29:52,347 INFO 2024-04-22 04:29:52,347 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-10000/stderr.txt 2024-04-22 04:29:53,189 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 04:29:53,190 DEBUG --- stdout --- 2024-04-22 04:29:53,190 DEBUG 0 /results/orders/authn-users-10000/stderr.txt 2024-04-22 04:29:53,190 DEBUG --- stderr --- 2024-04-22 04:29:53,190 DEBUG 2024-04-22 04:29:53,190 INFO - Download file (size 0k) 2024-04-22 04:29:53,190 INFO 2024-04-22 04:29:53,190 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:53,190 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:53,537 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:53,537 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:53,537 DEBUG --- stdout --- 2024-04-22 04:29:53,537 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:53,537 DEBUG --- stderr --- 2024-04-22 04:29:53,537 DEBUG source : /results/orders/authn-users-10000/stderr.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/stderr.txt 2024-04-22 04:29:53,538 INFO 2024-04-22 04:29:53,538 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:53,538 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:53,895 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:53,895 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:53,895 DEBUG --- stdout --- 2024-04-22 04:29:53,895 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:53,895 DEBUG --- stderr --- 2024-04-22 04:29:53,895 DEBUG 2024-04-22 04:29:53,895 INFO 2024-04-22 04:29:53,895 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-10000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/stderr.txt 2024-04-22 04:29:53,896 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 04:29:54,701 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:54,701 DEBUG --- stdout --- 2024-04-22 04:29:54,702 DEBUG tar: Removing leading `/' from member names 2024-04-22 04:29:54,702 DEBUG --- stderr --- 2024-04-22 04:29:54,702 DEBUG 2024-04-22 04:29:54,702 INFO 2024-04-22 04:29:54,702 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:54,702 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:55,024 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:55,024 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:55,024 DEBUG --- stdout --- 2024-04-22 04:29:55,024 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:55,024 DEBUG --- stderr --- 2024-04-22 04:29:55,024 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 04:29:55,025 INFO 2024-04-22 04:29:55,025 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:55,025 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:55,346 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:55,347 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:55,347 DEBUG --- stdout --- 2024-04-22 04:29:55,347 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:55,347 DEBUG --- stderr --- 2024-04-22 04:29:55,347 DEBUG 2024-04-22 04:29:55,347 INFO 2024-04-22 04:29:55,347 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-10000/stdout.txt" ]; then echo "/results/orders/authn-users-10000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-10000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-10000/stdout.txt file found" 2024-04-22 04:29:55,347 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 04:29:56,183 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 04:29:56,183 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:56,183 DEBUG --- stdout --- 2024-04-22 04:29:56,183 DEBUG /results/orders/authn-users-10000/stdout.txt file found 2024-04-22 04:29:56,183 DEBUG --- stderr --- 2024-04-22 04:29:56,183 DEBUG 2024-04-22 04:29:56,183 INFO 2024-04-22 04:29:56,183 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:56,183 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:56,502 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:56,502 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:56,502 DEBUG --- stdout --- 2024-04-22 04:29:56,502 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:56,502 DEBUG --- stderr --- 2024-04-22 04:29:56,502 DEBUG 2024-04-22 04:29:56,502 INFO 2024-04-22 04:29:56,503 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-10000/stdout.txt 2024-04-22 04:29:57,339 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 04:29:57,339 DEBUG --- stdout --- 2024-04-22 04:29:57,339 DEBUG 508 /results/orders/authn-users-10000/stdout.txt 2024-04-22 04:29:57,340 DEBUG --- stderr --- 2024-04-22 04:29:57,340 DEBUG 2024-04-22 04:29:57,340 INFO - Download file (size 508k) 2024-04-22 04:29:57,340 INFO 2024-04-22 04:29:57,340 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:57,340 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:57,696 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:57,696 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:57,697 DEBUG --- stdout --- 2024-04-22 04:29:57,697 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:57,697 DEBUG --- stderr --- 2024-04-22 04:29:57,697 DEBUG source : /results/orders/authn-users-10000/stdout.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/stdout.txt 2024-04-22 04:29:57,697 INFO 2024-04-22 04:29:57,697 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:57,697 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:58,087 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:58,087 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:58,087 DEBUG --- stdout --- 2024-04-22 04:29:58,087 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:58,087 DEBUG --- stderr --- 2024-04-22 04:29:58,087 DEBUG 2024-04-22 04:29:58,088 INFO 2024-04-22 04:29:58,088 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-10000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/stdout.txt 2024-04-22 04:29:58,088 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 04:29:59,192 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:59,192 DEBUG --- stdout --- 2024-04-22 04:29:59,192 DEBUG tar: Removing leading `/' from member names 2024-04-22 04:29:59,192 DEBUG --- stderr --- 2024-04-22 04:29:59,192 DEBUG ________________________________________________________________________________ [2024-04-22 04:29:59] authn-users-10000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-22 04:29:59,193 INFO 2024-04-22 04:29:59,193 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:59,193 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:59,518 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:59,518 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:59,518 DEBUG --- stdout --- 2024-04-22 04:29:59,518 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:59,518 DEBUG --- stderr --- 2024-04-22 04:29:59,518 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 04:29:59,519 INFO 2024-04-22 04:29:59,519 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:29:59,519 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:29:59,852 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:29:59,852 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:29:59,852 DEBUG --- stdout --- 2024-04-22 04:29:59,852 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:29:59,852 DEBUG --- stderr --- 2024-04-22 04:29:59,852 DEBUG 2024-04-22 04:29:59,853 INFO 2024-04-22 04:29:59,853 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- bash -c 'if [ -f "/results/orders/authn-users-10000/cmd.txt" ]; then echo "/results/orders/authn-users-10000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-10000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-10000/cmd.txt file found" 2024-04-22 04:29:59,853 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 04:30:00,712 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 04:30:00,712 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:30:00,713 DEBUG --- stdout --- 2024-04-22 04:30:00,713 DEBUG /results/orders/authn-users-10000/cmd.txt file found 2024-04-22 04:30:00,713 DEBUG --- stderr --- 2024-04-22 04:30:00,713 DEBUG 2024-04-22 04:30:00,713 INFO 2024-04-22 04:30:00,713 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:30:00,713 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:30:01,035 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:30:01,035 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:30:01,035 DEBUG --- stdout --- 2024-04-22 04:30:01,035 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:30:01,035 DEBUG --- stderr --- 2024-04-22 04:30:01,035 DEBUG 2024-04-22 04:30:01,035 INFO 2024-04-22 04:30:01,035 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68c5d5b884-p97w5 --stdin -- du -k /results/orders/authn-users-10000/cmd.txt 2024-04-22 04:30:01,872 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 04:30:01,873 DEBUG --- stdout --- 2024-04-22 04:30:01,873 DEBUG 4 /results/orders/authn-users-10000/cmd.txt 2024-04-22 04:30:01,873 DEBUG --- stderr --- 2024-04-22 04:30:01,873 DEBUG 2024-04-22 04:30:01,873 INFO - Download file (size 4k) 2024-04-22 04:30:01,873 INFO 2024-04-22 04:30:01,873 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:30:01,873 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:30:02,186 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:30:02,187 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:30:02,187 DEBUG --- stdout --- 2024-04-22 04:30:02,187 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:30:02,187 DEBUG --- stderr --- 2024-04-22 04:30:02,187 DEBUG source : /results/orders/authn-users-10000/cmd.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/cmd.txt 2024-04-22 04:30:02,187 INFO 2024-04-22 04:30:02,187 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 04:30:02,187 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 04:30:02,502 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 04:30:02,502 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:30:02,502 DEBUG --- stdout --- 2024-04-22 04:30:02,502 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 04:30:02,502 DEBUG --- stderr --- 2024-04-22 04:30:02,502 DEBUG 2024-04-22 04:30:02,503 INFO 2024-04-22 04:30:02,503 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68c5d5b884-p97w5:/results/orders/authn-users-10000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/cmd.txt 2024-04-22 04:30:02,503 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 04:30:03,262 INFO [loop_until]: OK (rc = 0) 2024-04-22 04:30:03,262 DEBUG --- stdout --- 2024-04-22 04:30:03,262 DEBUG tar: Removing leading `/' from member names 2024-04-22 04:30:03,262 DEBUG --- stderr --- 2024-04-22 04:30:03,263 DEBUG 2024-04-22 04:30:03,263 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/cmd.txt is small (1 lines), printing all lines: 2024-04-22 04:30:03,263 DEBUG ----- output ----- 2024-04-22 04:30:03,263 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-10000 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-22 04:30:03,263 INFO ________________________________________________________________________________ [2024-04-22 04:30:03] authn-users-10000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-22 04:30:03,263 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/rc.txt is small (1 lines), printing all lines: 2024-04-22 04:30:03,263 DEBUG ----- output ----- 2024-04-22 04:30:03,263 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-22 04:30:03,263 INFO ________________________________________________________________________________ [2024-04-22 04:30:03] authn-users-10000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-22 04:30:03,265 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-10000/stdout.txt is 8746 lines): 2024-04-22 04:30:03,265 DEBUG ----- output ----- 2024-04-22 04:30:03,265 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-22 04:30:03,265 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-22 04:30:03,265 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-22 04:30:03,265 INFO 2024-04-22 04:30:03,265 INFO ================================================================================ 2024-04-22 04:30:03,265 INFO 2024-04-22 04:27:36 5s elapsed 2024-04-22 04:30:03,265 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,265 INFO > Global (OK=4133 KO=0 ) 2024-04-22 04:30:03,265 INFO > userRestLogin (OK=4133 KO=0 ) 2024-04-22 04:30:03,265 INFO 2024-04-22 04:30:03,265 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,265 INFO [- ] 0% 2024-04-22 04:30:03,265 INFO waiting: 36155136 / active: 45 / done: 4133 2024-04-22 04:30:03,265 INFO ================================================================================ 2024-04-22 04:30:03,265 INFO 2024-04-22 04:30:03,265 INFO 2024-04-22 04:30:03,265 INFO ================================================================================ 2024-04-22 04:30:03,265 INFO 2024-04-22 04:27:41 10s elapsed 2024-04-22 04:30:03,265 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,265 INFO > Global (OK=16622 KO=0 ) 2024-04-22 04:30:03,265 INFO > userRestLogin (OK=16622 KO=0 ) 2024-04-22 04:30:03,265 INFO 2024-04-22 04:30:03,266 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,266 INFO [- ] 0% 2024-04-22 04:30:03,266 INFO waiting: 36142623 / active: 69 / done: 16622 2024-04-22 04:30:03,266 INFO ================================================================================ 2024-04-22 04:30:03,266 INFO 2024-04-22 04:30:03,266 INFO 2024-04-22 04:30:03,266 INFO ================================================================================ 2024-04-22 04:30:03,266 INFO 2024-04-22 04:27:46 15s elapsed 2024-04-22 04:30:03,266 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,266 INFO > Global (OK=37439 KO=0 ) 2024-04-22 04:30:03,266 INFO > userRestLogin (OK=37439 KO=0 ) 2024-04-22 04:30:03,266 INFO 2024-04-22 04:30:03,266 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,266 INFO [- ] 0% 2024-04-22 04:30:03,266 INFO waiting: 36121783 / active: 92 / done: 37439 2024-04-22 04:30:03,266 INFO ================================================================================ 2024-04-22 04:30:03,266 INFO 2024-04-22 04:30:03,266 INFO 2024-04-22 04:30:03,266 INFO ================================================================================ 2024-04-22 04:30:03,266 INFO 2024-04-22 04:27:51 20s elapsed 2024-04-22 04:30:03,266 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,266 INFO > Global (OK=66442 KO=0 ) 2024-04-22 04:30:03,266 INFO > userRestLogin (OK=66442 KO=0 ) 2024-04-22 04:30:03,266 INFO 2024-04-22 04:30:03,266 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,266 INFO [- ] 0% 2024-04-22 04:30:03,266 INFO waiting: 36092606 / active: 266 / done: 66442 2024-04-22 04:30:03,266 INFO ================================================================================ 2024-04-22 04:30:03,266 INFO 2024-04-22 04:30:03,266 INFO 2024-04-22 04:30:03,266 INFO ================================================================================ 2024-04-22 04:30:03,266 INFO 2024-04-22 04:27:56 25s elapsed 2024-04-22 04:30:03,266 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,266 INFO > Global (OK=103956 KO=0 ) 2024-04-22 04:30:03,266 INFO > userRestLogin (OK=103956 KO=0 ) 2024-04-22 04:30:03,266 INFO 2024-04-22 04:30:03,266 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,266 INFO [- ] 0% 2024-04-22 04:30:03,266 INFO waiting: 36055097 / active: 263 / done: 103954 2024-04-22 04:30:03,267 INFO ================================================================================ 2024-04-22 04:30:03,267 INFO 2024-04-22 04:30:03,267 INFO 2024-04-22 04:30:03,267 INFO ================================================================================ 2024-04-22 04:30:03,267 INFO 2024-04-22 04:28:01 30s elapsed 2024-04-22 04:30:03,267 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,267 INFO > Global (OK=148344 KO=0 ) 2024-04-22 04:30:03,267 INFO > userRestLogin (OK=148344 KO=0 ) 2024-04-22 04:30:03,267 INFO 2024-04-22 04:30:03,267 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,267 INFO [- ] 0% 2024-04-22 04:30:03,267 INFO waiting: 36009508 / active: 1465 / done: 148341 2024-04-22 04:30:03,267 INFO ================================================================================ 2024-04-22 04:30:03,267 INFO 2024-04-22 04:30:03,267 INFO 2024-04-22 04:30:03,267 INFO ================================================================================ 2024-04-22 04:30:03,267 INFO 2024-04-22 04:28:06 35s elapsed 2024-04-22 04:30:03,267 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,267 INFO > Global (OK=198453 KO=0 ) 2024-04-22 04:30:03,267 INFO > userRestLogin (OK=198453 KO=0 ) 2024-04-22 04:30:03,267 INFO 2024-04-22 04:30:03,267 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,267 INFO [- ] 0% 2024-04-22 04:30:03,267 INFO waiting: 35959535 / active: 1326 / done: 198453 2024-04-22 04:30:03,267 INFO ================================================================================ 2024-04-22 04:30:03,267 INFO 2024-04-22 04:30:03,267 INFO 2024-04-22 04:30:03,267 INFO ================================================================================ 2024-04-22 04:30:03,267 INFO 2024-04-22 04:28:11 40s elapsed 2024-04-22 04:30:03,267 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,267 INFO > Global (OK=248355 KO=0 ) 2024-04-22 04:30:03,267 INFO > userRestLogin (OK=248355 KO=0 ) 2024-04-22 04:30:03,267 INFO 2024-04-22 04:30:03,267 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,267 INFO [- ] 0% 2024-04-22 04:30:03,267 INFO waiting: 35909794 / active: 1165 / done: 248355 2024-04-22 04:30:03,267 INFO ================================================================================ 2024-04-22 04:30:03,267 INFO 2024-04-22 04:30:03,267 INFO 2024-04-22 04:30:03,267 INFO [...] 2024-04-22 04:30:03,270 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,270 INFO > Global (OK=35902900 KO=0 ) 2024-04-22 04:30:03,270 INFO > userRestLogin (OK=35902900 KO=0 ) 2024-04-22 04:30:03,270 INFO 2024-04-22 04:30:03,270 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,270 INFO [#########################################################################-] 99% 2024-04-22 04:30:03,270 INFO waiting: 250203 / active: 6212 / done: 35902899 2024-04-22 04:30:03,270 INFO ================================================================================ 2024-04-22 04:30:03,270 INFO 2024-04-22 04:30:03,270 INFO 2024-04-22 04:30:03,270 INFO ================================================================================ 2024-04-22 04:30:03,270 INFO 2024-04-22 05:27:41 3610s elapsed 2024-04-22 04:30:03,270 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,270 INFO > Global (OK=35953108 KO=0 ) 2024-04-22 04:30:03,270 INFO > userRestLogin (OK=35953108 KO=0 ) 2024-04-22 04:30:03,270 INFO 2024-04-22 04:30:03,270 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,270 INFO [#########################################################################-] 99% 2024-04-22 04:30:03,270 INFO waiting: 200117 / active: 6090 / done: 35953107 2024-04-22 04:30:03,270 INFO ================================================================================ 2024-04-22 04:30:03,270 INFO 2024-04-22 04:30:03,270 INFO 2024-04-22 04:30:03,270 INFO ================================================================================ 2024-04-22 04:30:03,270 INFO 2024-04-22 05:27:46 3615s elapsed 2024-04-22 04:30:03,270 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,270 INFO > Global (OK=36003671 KO=0 ) 2024-04-22 04:30:03,270 INFO > userRestLogin (OK=36003671 KO=0 ) 2024-04-22 04:30:03,270 INFO 2024-04-22 04:30:03,270 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,270 INFO [#########################################################################-] 99% 2024-04-22 04:30:03,270 INFO waiting: 149804 / active: 5841 / done: 36003669 2024-04-22 04:30:03,271 INFO ================================================================================ 2024-04-22 04:30:03,271 INFO 2024-04-22 04:30:03,271 INFO 2024-04-22 04:30:03,271 INFO ================================================================================ 2024-04-22 04:30:03,271 INFO 2024-04-22 05:27:51 3620s elapsed 2024-04-22 04:30:03,271 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,271 INFO > Global (OK=36053766 KO=0 ) 2024-04-22 04:30:03,271 INFO > userRestLogin (OK=36053766 KO=0 ) 2024-04-22 04:30:03,271 INFO 2024-04-22 04:30:03,271 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,271 INFO [#########################################################################-] 99% 2024-04-22 04:30:03,271 INFO waiting: 99905 / active: 5646 / done: 36053763 2024-04-22 04:30:03,271 INFO ================================================================================ 2024-04-22 04:30:03,271 INFO 2024-04-22 04:30:03,271 INFO 2024-04-22 04:30:03,271 INFO ================================================================================ 2024-04-22 04:30:03,271 INFO 2024-04-22 05:27:56 3625s elapsed 2024-04-22 04:30:03,271 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,271 INFO > Global (OK=36103794 KO=0 ) 2024-04-22 04:30:03,271 INFO > userRestLogin (OK=36103794 KO=0 ) 2024-04-22 04:30:03,271 INFO 2024-04-22 04:30:03,271 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,271 INFO [#########################################################################-] 99% 2024-04-22 04:30:03,271 INFO waiting: 49824 / active: 5699 / done: 36103791 2024-04-22 04:30:03,271 INFO ================================================================================ 2024-04-22 04:30:03,271 INFO 2024-04-22 04:30:03,271 INFO 2024-04-22 04:30:03,271 INFO ================================================================================ 2024-04-22 04:30:03,271 INFO 2024-04-22 05:28:01 3630s elapsed 2024-04-22 04:30:03,271 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,271 INFO > Global (OK=36154084 KO=0 ) 2024-04-22 04:30:03,271 INFO > userRestLogin (OK=36154084 KO=0 ) 2024-04-22 04:30:03,271 INFO 2024-04-22 04:30:03,271 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,271 INFO [#########################################################################-] 99% 2024-04-22 04:30:03,271 INFO waiting: 0 / active: 5231 / done: 36154083 2024-04-22 04:30:03,271 INFO ================================================================================ 2024-04-22 04:30:03,271 INFO 2024-04-22 04:30:03,271 INFO 2024-04-22 04:30:03,271 INFO ================================================================================ 2024-04-22 04:30:03,272 INFO 2024-04-22 05:28:02 3631s elapsed 2024-04-22 04:30:03,272 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 04:30:03,272 INFO > Global (OK=36159314 KO=0 ) 2024-04-22 04:30:03,272 INFO > userRestLogin (OK=36159314 KO=0 ) 2024-04-22 04:30:03,272 INFO 2024-04-22 04:30:03,272 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 04:30:03,272 INFO [##########################################################################]100% 2024-04-22 04:30:03,272 INFO waiting: 0 / active: 0 / done: 36159314 2024-04-22 04:30:03,272 INFO ================================================================================ 2024-04-22 04:30:03,272 INFO 2024-04-22 04:30:03,272 INFO Simulation com.forgerock.gatling.Loadtest completed in 3631 seconds 2024-04-22 04:30:03,272 INFO 2024-04-22 04:30:03,272 INFO ---------------------- 2024-04-22 04:30:03,272 INFO [22/Apr/2024 05:28:02] Create tar file authn-users-10000.tar.gz 2024-04-22 04:30:03,272 INFO ---------------------- 2024-04-22 04:30:03,272 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-22 04:30:03,272 INFO RESULT_FOLDER=authn-users-10000 2024-04-22 04:30:03,272 INFO SIMULATION_PATH=authn-users-10000/loadtest-20240422032730531 2024-04-22 04:30:03,272 INFO PWD=/results/gatling/authn-users-10000/loadtest-20240422032730531 2024-04-22 04:30:03,272 INFO [cmd] tar -czvf /results/gatling//authn-users-10000.tar.gz --exclude=simulation.log * 2024-04-22 04:30:03,272 INFO /results/gatling 2024-04-22 04:30:03,272 INFO 2024-04-22 04:30:03,272 INFO ---------------------- 2024-04-22 04:30:03,272 INFO [22/Apr/2024 05:28:02] Update /results/gatling//latest 2024-04-22 04:30:03,272 INFO ---------------------- 2024-04-22 04:30:03,272 INFO 2024-04-22 04:30:03,272 INFO ---------------------- 2024-04-22 04:30:03,272 INFO [22/Apr/2024 05:28:02] Update /results/gatling//latest.tar.gz 2024-04-22 04:30:03,272 INFO ---------------------- 2024-04-22 04:30:03,272 INFO ________________________________________________________________________________ [2024-04-22 04:30:03] authn-users-10000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-22 04:30:03,273 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/06_authn-users-10000/stderr.txt is small (0 lines), printing all lines: 2024-04-22 04:30:03,273 DEBUG ----- output ----- 2024-04-22 04:30:03,273 DEBUG 2024-04-22 04:30:03,273 INFO ________________________________________________________________________________ [2024-04-22 04:30:03] authn-users-10000 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-22 04:30:03] authn-users-10000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-22 04:30:03] authn-users-10000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped