--Task-- name: authn-users-3000 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-100'] preceding_task: authn-users-100 options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 3000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-21 23:16:13] authn-users-3000 pre : N/A ________________________________________________________________________________ [2024-04-21 23:16:13] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-21 23:16:13] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-3000/stdout.txt Pod log : /results/orders/authn-users-3000 2024-04-21 23:16:13,152 INFO 2024-04-21 23:16:13,152 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-21 23:16:13,152 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:13,458 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:13,458 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:13,458 DEBUG --- stdout --- 2024-04-21 23:16:13,458 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:13,458 DEBUG --- stderr --- 2024-04-21 23:16:13,458 DEBUG ________________________________________________________________________________ [2024-04-21 23:16:13] authn-users-3000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-21 23:16:13,459 INFO 2024-04-21 23:16:13,459 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-21 23:16:13,459 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:13,857 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:13,858 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:13,858 DEBUG --- stdout --- 2024-04-21 23:16:13,858 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:13,858 DEBUG --- stderr --- 2024-04-21 23:16:13,858 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-3000 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-21 23:16:13] authn-users-3000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-21 23:16:13,859 INFO 2024-04-21 23:16:13,859 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-21 23:16:13,859 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:14,168 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:14,168 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:14,168 DEBUG --- stdout --- 2024-04-21 23:16:14,168 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:14,168 DEBUG --- stderr --- 2024-04-21 23:16:14,168 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/order.json { "name": "authn-users-3000", "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-3000 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/authn-users-3000", "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=3000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-21 23:16:14] authn-users-3000 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-21 23:16:14,169 INFO 2024-04-21 23:16:14,170 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-21 23:16:14,170 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-21 23:16:14,399 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-21 23:16:14,399 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:14,399 DEBUG --- stdout --- 2024-04-21 23:16:14,399 DEBUG WAITING 2024-04-21 23:16:14,399 DEBUG --- stderr --- 2024-04-21 23:16:14,399 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-21 23:16:14] authn-users-3000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-21 23:16:14,400 INFO 2024-04-21 23:16:14,400 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-21 23:16:14,400 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:14,708 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:14,708 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:14,708 DEBUG --- stdout --- 2024-04-21 23:16:14,708 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:14,708 DEBUG --- stderr --- 2024-04-21 23:16:14,708 DEBUG 2024-04-21 23:16:14,709 INFO 2024-04-21 23:16:14,709 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/02_authn-users-3000/order.json overseer-0-68c5d5b884-p97w5:/results/orders/order.json 2024-04-21 23:16:14,709 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-21 23:16:16,131 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:16,131 DEBUG --- stdout --- 2024-04-21 23:16:16,131 DEBUG 2024-04-21 23:16:16,131 DEBUG --- stderr --- 2024-04-21 23:16:16,132 DEBUG Order has been successfully forwarded to overseer [2024-04-21 23:16:16] - INFO: Check if Gatling simulation started successfully 2024-04-21 23:16:16,132 INFO 2024-04-21 23:16:16,132 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-21 23:16:16,132 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-21 23:16:16,451 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-21 23:16:16,451 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:16,451 DEBUG --- stdout --- 2024-04-21 23:16:16,451 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-21 23:16:16,451 DEBUG --- stderr --- 2024-04-21 23:16:16,451 DEBUG 2024-04-21 23:16:16,451 INFO 2024-04-21 23:16:16,451 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-3000/stdout.txt | grep "started..." 2024-04-21 23:16:16,451 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-21 23:16:17,210 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-21 23:16:23,002 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-21 23:16:28,845 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-21 23:16:34,663 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-21 23:16:34,663 INFO [loop_until]: OK (rc = 0) 2024-04-21 23:16:34,663 DEBUG --- stdout --- 2024-04-21 23:16:34,663 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 00:16:19.342 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 00:16:25.360 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 00:16:25.363 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 00:16:27.644 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-04-21 23:16:34,663 DEBUG --- stderr --- 2024-04-21 23:16:34,663 DEBUG ________________________________________________________________________________ [2024-04-21 23:16:34] authn-users-3000 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 04s, 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 00:18:45] authn-users-3000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-22 00:18:45,679 INFO 2024-04-22 00:18:45,680 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 00:18:45,680 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:46,031 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:46,031 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:46,031 DEBUG --- stdout --- 2024-04-22 00:18:46,031 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:46,031 DEBUG --- stderr --- 2024-04-22 00:18:46,031 DEBUG 2024-04-22 00:18:46,031 INFO 2024-04-22 00:18:46,032 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 00:18:46,032 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 00:18:46,840 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:46,840 DEBUG --- stdout --- 2024-04-22 00:18:46,840 DEBUG 2024-04-22 00:18:46,840 DEBUG --- stderr --- 2024-04-22 00:18:46,840 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-22 00:18:46] authn-users-3000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-22 00:18:46] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/order.json 2024-04-22 00:18:46,841 INFO 2024-04-22 00:18:46,841 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 00:18:46,842 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:47,173 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:47,174 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:47,174 DEBUG --- stdout --- 2024-04-22 00:18:47,174 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:47,174 DEBUG --- stderr --- 2024-04-22 00:18:47,174 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 00:18:47,174 INFO 2024-04-22 00:18:47,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-22 00:18:47,174 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:47,542 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:47,542 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:47,542 DEBUG --- stdout --- 2024-04-22 00:18:47,542 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:47,542 DEBUG --- stderr --- 2024-04-22 00:18:47,542 DEBUG 2024-04-22 00:18:47,543 INFO 2024-04-22 00:18:47,543 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-3000/rc.txt" ]; then echo "/results/orders/authn-users-3000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/rc.txt file found" 2024-04-22 00:18:47,543 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 00:18:48,405 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 00:18:48,406 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:48,407 DEBUG --- stdout --- 2024-04-22 00:18:48,407 DEBUG /results/orders/authn-users-3000/rc.txt file found 2024-04-22 00:18:48,407 DEBUG --- stderr --- 2024-04-22 00:18:48,407 DEBUG 2024-04-22 00:18:48,408 INFO 2024-04-22 00:18:48,408 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 00:18:48,408 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:48,717 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:48,717 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:48,717 DEBUG --- stdout --- 2024-04-22 00:18:48,717 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:48,717 DEBUG --- stderr --- 2024-04-22 00:18:48,717 DEBUG 2024-04-22 00:18:48,718 INFO 2024-04-22 00:18:48,718 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-3000/rc.txt 2024-04-22 00:18:49,546 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 00:18:49,546 DEBUG --- stdout --- 2024-04-22 00:18:49,547 DEBUG 4 /results/orders/authn-users-3000/rc.txt 2024-04-22 00:18:49,547 DEBUG --- stderr --- 2024-04-22 00:18:49,547 DEBUG 2024-04-22 00:18:49,547 INFO - Download file (size 4k) 2024-04-22 00:18:49,547 INFO 2024-04-22 00:18:49,547 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 00:18:49,547 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:49,853 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:49,853 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:49,853 DEBUG --- stdout --- 2024-04-22 00:18:49,853 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:49,853 DEBUG --- stderr --- 2024-04-22 00:18:49,853 DEBUG source : /results/orders/authn-users-3000/rc.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/rc.txt 2024-04-22 00:18:49,854 INFO 2024-04-22 00:18:49,854 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 00:18:49,854 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:50,229 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:50,229 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:50,229 DEBUG --- stdout --- 2024-04-22 00:18:50,230 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:50,230 DEBUG --- stderr --- 2024-04-22 00:18:50,230 DEBUG 2024-04-22 00:18:50,230 INFO 2024-04-22 00:18:50,230 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-3000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/rc.txt 2024-04-22 00:18:50,230 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 00:18:50,984 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:50,984 DEBUG --- stdout --- 2024-04-22 00:18:50,984 DEBUG tar: Removing leading `/' from member names 2024-04-22 00:18:50,985 DEBUG --- stderr --- 2024-04-22 00:18:50,985 DEBUG 2024-04-22 00:18:50,985 INFO 2024-04-22 00:18:50,985 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 00:18:50,985 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:51,299 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:51,299 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:51,299 DEBUG --- stdout --- 2024-04-22 00:18:51,299 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:51,299 DEBUG --- stderr --- 2024-04-22 00:18:51,299 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 00:18:51,300 INFO 2024-04-22 00:18:51,300 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 00:18:51,300 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:51,607 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:51,607 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:51,608 DEBUG --- stdout --- 2024-04-22 00:18:51,608 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:51,608 DEBUG --- stderr --- 2024-04-22 00:18:51,608 DEBUG 2024-04-22 00:18:51,608 INFO 2024-04-22 00:18:51,608 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-3000/stderr.txt" ]; then echo "/results/orders/authn-users-3000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/stderr.txt file found" 2024-04-22 00:18:51,608 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 00:18:52,429 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 00:18:52,429 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:52,430 DEBUG --- stdout --- 2024-04-22 00:18:52,430 DEBUG /results/orders/authn-users-3000/stderr.txt file found 2024-04-22 00:18:52,430 DEBUG --- stderr --- 2024-04-22 00:18:52,430 DEBUG 2024-04-22 00:18:52,431 INFO 2024-04-22 00:18:52,431 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 00:18:52,431 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:52,740 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:52,740 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:52,740 DEBUG --- stdout --- 2024-04-22 00:18:52,740 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:52,741 DEBUG --- stderr --- 2024-04-22 00:18:52,741 DEBUG 2024-04-22 00:18:52,741 INFO 2024-04-22 00:18:52,741 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-3000/stderr.txt 2024-04-22 00:18:53,559 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 00:18:53,560 DEBUG --- stdout --- 2024-04-22 00:18:53,560 DEBUG 0 /results/orders/authn-users-3000/stderr.txt 2024-04-22 00:18:53,560 DEBUG --- stderr --- 2024-04-22 00:18:53,560 DEBUG 2024-04-22 00:18:53,560 INFO - Download file (size 0k) 2024-04-22 00:18:53,560 INFO 2024-04-22 00:18:53,561 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 00:18:53,561 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:53,878 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:53,879 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:53,879 DEBUG --- stdout --- 2024-04-22 00:18:53,879 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:53,879 DEBUG --- stderr --- 2024-04-22 00:18:53,879 DEBUG source : /results/orders/authn-users-3000/stderr.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/stderr.txt 2024-04-22 00:18:53,879 INFO 2024-04-22 00:18:53,879 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 00:18:53,879 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:54,187 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:54,187 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:54,187 DEBUG --- stdout --- 2024-04-22 00:18:54,187 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:54,187 DEBUG --- stderr --- 2024-04-22 00:18:54,187 DEBUG 2024-04-22 00:18:54,188 INFO 2024-04-22 00:18:54,188 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-3000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/stderr.txt 2024-04-22 00:18:54,188 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 00:18:55,004 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:55,004 DEBUG --- stdout --- 2024-04-22 00:18:55,004 DEBUG tar: Removing leading `/' from member names 2024-04-22 00:18:55,005 DEBUG --- stderr --- 2024-04-22 00:18:55,005 DEBUG 2024-04-22 00:18:55,005 INFO 2024-04-22 00:18:55,005 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 00:18:55,005 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:55,321 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:55,321 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:55,321 DEBUG --- stdout --- 2024-04-22 00:18:55,321 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:55,321 DEBUG --- stderr --- 2024-04-22 00:18:55,321 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 00:18:55,322 INFO 2024-04-22 00:18:55,322 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 00:18:55,322 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:55,632 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:55,632 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:55,633 DEBUG --- stdout --- 2024-04-22 00:18:55,633 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:55,633 DEBUG --- stderr --- 2024-04-22 00:18:55,633 DEBUG 2024-04-22 00:18:55,633 INFO 2024-04-22 00:18:55,633 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-3000/stdout.txt" ]; then echo "/results/orders/authn-users-3000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/stdout.txt file found" 2024-04-22 00:18:55,633 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 00:18:56,456 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 00:18:56,456 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:56,456 DEBUG --- stdout --- 2024-04-22 00:18:56,456 DEBUG /results/orders/authn-users-3000/stdout.txt file found 2024-04-22 00:18:56,456 DEBUG --- stderr --- 2024-04-22 00:18:56,456 DEBUG 2024-04-22 00:18:56,456 INFO 2024-04-22 00:18:56,456 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 00:18:56,456 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:56,767 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:56,767 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:56,767 DEBUG --- stdout --- 2024-04-22 00:18:56,767 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:56,767 DEBUG --- stderr --- 2024-04-22 00:18:56,768 DEBUG 2024-04-22 00:18:56,768 INFO 2024-04-22 00:18:56,768 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-3000/stdout.txt 2024-04-22 00:18:57,592 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 00:18:57,592 DEBUG --- stdout --- 2024-04-22 00:18:57,592 DEBUG 508 /results/orders/authn-users-3000/stdout.txt 2024-04-22 00:18:57,592 DEBUG --- stderr --- 2024-04-22 00:18:57,592 DEBUG 2024-04-22 00:18:57,592 INFO - Download file (size 508k) 2024-04-22 00:18:57,593 INFO 2024-04-22 00:18:57,593 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 00:18:57,593 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:57,900 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:57,900 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:57,900 DEBUG --- stdout --- 2024-04-22 00:18:57,900 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:57,900 DEBUG --- stderr --- 2024-04-22 00:18:57,900 DEBUG source : /results/orders/authn-users-3000/stdout.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/stdout.txt 2024-04-22 00:18:57,901 INFO 2024-04-22 00:18:57,901 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 00:18:57,901 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:58,216 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:58,216 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:58,216 DEBUG --- stdout --- 2024-04-22 00:18:58,216 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:58,217 DEBUG --- stderr --- 2024-04-22 00:18:58,217 DEBUG 2024-04-22 00:18:58,217 INFO 2024-04-22 00:18:58,217 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-3000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/stdout.txt 2024-04-22 00:18:58,217 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 00:18:59,303 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:59,303 DEBUG --- stdout --- 2024-04-22 00:18:59,303 DEBUG tar: Removing leading `/' from member names 2024-04-22 00:18:59,303 DEBUG --- stderr --- 2024-04-22 00:18:59,303 DEBUG ________________________________________________________________________________ [2024-04-22 00:18:59] authn-users-3000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-22 00:18:59,304 INFO 2024-04-22 00:18:59,304 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-22 00:18:59,304 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:59,614 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:59,615 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:59,615 DEBUG --- stdout --- 2024-04-22 00:18:59,615 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:59,615 DEBUG --- stderr --- 2024-04-22 00:18:59,615 DEBUG Checking pod: overseer-0-68c5d5b884-p97w5 for file exists, if not wait. 2024-04-22 00:18:59,615 INFO 2024-04-22 00:18:59,615 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 00:18:59,615 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:18:59,935 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:18:59,935 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:18:59,935 DEBUG --- stdout --- 2024-04-22 00:18:59,935 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:18:59,935 DEBUG --- stderr --- 2024-04-22 00:18:59,935 DEBUG 2024-04-22 00:18:59,936 INFO 2024-04-22 00:18:59,936 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-3000/cmd.txt" ]; then echo "/results/orders/authn-users-3000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-3000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-3000/cmd.txt file found" 2024-04-22 00:18:59,936 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-22 00:19:00,770 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-22 00:19:00,771 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:00,771 DEBUG --- stdout --- 2024-04-22 00:19:00,771 DEBUG /results/orders/authn-users-3000/cmd.txt file found 2024-04-22 00:19:00,771 DEBUG --- stderr --- 2024-04-22 00:19:00,771 DEBUG 2024-04-22 00:19:00,771 INFO 2024-04-22 00:19:00,771 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 00:19:00,771 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:19:01,082 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:19:01,082 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:01,082 DEBUG --- stdout --- 2024-04-22 00:19:01,082 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:19:01,082 DEBUG --- stderr --- 2024-04-22 00:19:01,082 DEBUG 2024-04-22 00:19:01,082 INFO 2024-04-22 00:19:01,083 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-3000/cmd.txt 2024-04-22 00:19:01,907 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-22 00:19:01,907 DEBUG --- stdout --- 2024-04-22 00:19:01,907 DEBUG 4 /results/orders/authn-users-3000/cmd.txt 2024-04-22 00:19:01,908 DEBUG --- stderr --- 2024-04-22 00:19:01,908 DEBUG 2024-04-22 00:19:01,908 INFO - Download file (size 4k) 2024-04-22 00:19:01,908 INFO 2024-04-22 00:19:01,908 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 00:19:01,908 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:19:02,217 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:19:02,217 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:02,217 DEBUG --- stdout --- 2024-04-22 00:19:02,217 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:19:02,217 DEBUG --- stderr --- 2024-04-22 00:19:02,217 DEBUG source : /results/orders/authn-users-3000/cmd.txt @ overseer-0-68c5d5b884-p97w5 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/cmd.txt 2024-04-22 00:19:02,217 INFO 2024-04-22 00:19:02,217 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 00:19:02,217 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-22 00:19:02,528 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-22 00:19:02,528 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:02,528 DEBUG --- stdout --- 2024-04-22 00:19:02,529 DEBUG overseer-0-68c5d5b884-p97w5 2024-04-22 00:19:02,529 DEBUG --- stderr --- 2024-04-22 00:19:02,529 DEBUG 2024-04-22 00:19:02,529 INFO 2024-04-22 00:19:02,529 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-3000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/cmd.txt 2024-04-22 00:19:02,529 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-22 00:19:03,310 INFO [loop_until]: OK (rc = 0) 2024-04-22 00:19:03,310 DEBUG --- stdout --- 2024-04-22 00:19:03,311 DEBUG tar: Removing leading `/' from member names 2024-04-22 00:19:03,311 DEBUG --- stderr --- 2024-04-22 00:19:03,311 DEBUG 2024-04-22 00:19:03,311 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/cmd.txt is small (1 lines), printing all lines: 2024-04-22 00:19:03,311 DEBUG ----- output ----- 2024-04-22 00:19:03,311 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-3000 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-22 00:19:03,311 INFO ________________________________________________________________________________ [2024-04-22 00:19:03] authn-users-3000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-22 00:19:03,311 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/rc.txt is small (1 lines), printing all lines: 2024-04-22 00:19:03,311 DEBUG ----- output ----- 2024-04-22 00:19:03,312 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-22 00:19:03,312 INFO ________________________________________________________________________________ [2024-04-22 00:19:03] authn-users-3000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-22 00:19:03,313 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/02_authn-users-3000/stdout.txt is 8754 lines): 2024-04-22 00:19:03,314 DEBUG ----- output ----- 2024-04-22 00:19:03,314 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-22 00:19:03,314 INFO 00:16:19.342 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-22 00:19:03,314 INFO 00:16:25.360 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-22 00:19:03,314 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-22 00:19:03,314 INFO ^ 2024-04-22 00:19:03,314 INFO 00:16:25.363 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-22 00:19:03,314 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-22 00:19:03,314 INFO ^ 2024-04-22 00:19:03,314 INFO 00:16:27.644 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-22 00:19:03,314 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-22 00:19:03,314 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-22 00:19:03,314 INFO 2024-04-22 00:19:03,314 INFO ================================================================================ 2024-04-22 00:19:03,314 INFO 2024-04-22 00:16:37 5s elapsed 2024-04-22 00:19:03,314 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,314 INFO > Global (OK=1249 KO=0 ) 2024-04-22 00:19:03,314 INFO > userRestLogin (OK=1249 KO=0 ) 2024-04-22 00:19:03,314 INFO 2024-04-22 00:19:03,314 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,314 INFO [- ] 0% 2024-04-22 00:19:03,314 INFO waiting: 10844379 / active: 5 / done: 1249 2024-04-22 00:19:03,314 INFO ================================================================================ 2024-04-22 00:19:03,315 INFO 2024-04-22 00:19:03,315 INFO 2024-04-22 00:19:03,315 INFO ================================================================================ 2024-04-22 00:19:03,315 INFO 2024-04-22 00:16:42 10s elapsed 2024-04-22 00:19:03,315 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,315 INFO > Global (OK=4999 KO=0 ) 2024-04-22 00:19:03,315 INFO > userRestLogin (OK=4999 KO=0 ) 2024-04-22 00:19:03,315 INFO 2024-04-22 00:19:03,315 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,315 INFO [- ] 0% 2024-04-22 00:19:03,315 INFO waiting: 10840626 / active: 8 / done: 4999 2024-04-22 00:19:03,315 INFO ================================================================================ 2024-04-22 00:19:03,315 INFO 2024-04-22 00:19:03,315 INFO 2024-04-22 00:19:03,315 INFO ================================================================================ 2024-04-22 00:19:03,315 INFO 2024-04-22 00:16:47 15s elapsed 2024-04-22 00:19:03,315 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,315 INFO > Global (OK=11248 KO=0 ) 2024-04-22 00:19:03,315 INFO > userRestLogin (OK=11248 KO=0 ) 2024-04-22 00:19:03,315 INFO 2024-04-22 00:19:03,315 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,315 INFO [- ] 0% 2024-04-22 00:19:03,315 INFO waiting: 10834373 / active: 13 / done: 11247 2024-04-22 00:19:03,315 INFO ================================================================================ 2024-04-22 00:19:03,315 INFO 2024-04-22 00:19:03,315 INFO 2024-04-22 00:19:03,315 INFO ================================================================================ 2024-04-22 00:19:03,315 INFO 2024-04-22 00:16:52 20s elapsed 2024-04-22 00:19:03,315 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,315 INFO > Global (OK=19999 KO=0 ) 2024-04-22 00:19:03,315 INFO > userRestLogin (OK=19999 KO=0 ) 2024-04-22 00:19:03,315 INFO 2024-04-22 00:19:03,315 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,315 INFO [- ] 0% 2024-04-22 00:19:03,315 INFO waiting: 10825618 / active: 16 / done: 19999 2024-04-22 00:19:03,315 INFO ================================================================================ 2024-04-22 00:19:03,315 INFO 2024-04-22 00:19:03,316 INFO 2024-04-22 00:19:03,316 INFO ================================================================================ 2024-04-22 00:19:03,316 INFO 2024-04-22 00:16:57 25s elapsed 2024-04-22 00:19:03,316 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,316 INFO > Global (OK=31242 KO=0 ) 2024-04-22 00:19:03,316 INFO > userRestLogin (OK=31242 KO=0 ) 2024-04-22 00:19:03,316 INFO 2024-04-22 00:19:03,316 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,316 INFO [- ] 0% 2024-04-22 00:19:03,316 INFO waiting: 10814367 / active: 24 / done: 31242 2024-04-22 00:19:03,316 INFO ================================================================================ 2024-04-22 00:19:03,316 INFO 2024-04-22 00:19:03,316 INFO 2024-04-22 00:19:03,316 INFO ================================================================================ 2024-04-22 00:19:03,316 INFO 2024-04-22 00:17:02 30s elapsed 2024-04-22 00:19:03,316 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,316 INFO > Global (OK=44947 KO=0 ) 2024-04-22 00:19:03,316 INFO > userRestLogin (OK=44947 KO=0 ) 2024-04-22 00:19:03,316 INFO 2024-04-22 00:19:03,316 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,316 INFO [- ] 0% 2024-04-22 00:19:03,316 INFO waiting: 10800656 / active: 30 / done: 44947 2024-04-22 00:19:03,316 INFO ================================================================================ 2024-04-22 00:19:03,316 INFO 2024-04-22 00:19:03,316 INFO 2024-04-22 00:19:03,316 INFO ================================================================================ 2024-04-22 00:19:03,316 INFO 2024-04-22 00:17:07 35s elapsed 2024-04-22 00:19:03,316 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,316 INFO > Global (OK=59899 KO=0 ) 2024-04-22 00:19:03,316 INFO > userRestLogin (OK=59899 KO=0 ) 2024-04-22 00:19:03,316 INFO 2024-04-22 00:19:03,316 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,316 INFO [- ] 0% 2024-04-22 00:19:03,316 INFO waiting: 10785711 / active: 23 / done: 59899 2024-04-22 00:19:03,316 INFO ================================================================================ 2024-04-22 00:19:03,316 INFO 2024-04-22 00:19:03,316 INFO 2024-04-22 00:19:03,316 INFO ================================================================================ 2024-04-22 00:19:03,316 INFO 2024-04-22 00:17:12 40s elapsed 2024-04-22 00:19:03,316 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,316 INFO > Global (OK=74984 KO=0 ) 2024-04-22 00:19:03,316 INFO [...] 2024-04-22 00:19:03,319 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,319 INFO > Global (OK=10771281 KO=0 ) 2024-04-22 00:19:03,319 INFO > userRestLogin (OK=10771281 KO=0 ) 2024-04-22 00:19:03,319 INFO 2024-04-22 00:19:03,319 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,319 INFO [#########################################################################-] 99% 2024-04-22 00:19:03,320 INFO waiting: 74319 / active: 33 / done: 10771281 2024-04-22 00:19:03,320 INFO ================================================================================ 2024-04-22 00:19:03,320 INFO 2024-04-22 00:19:03,320 INFO 2024-04-22 00:19:03,320 INFO ================================================================================ 2024-04-22 00:19:03,320 INFO 2024-04-22 01:16:42 3610s elapsed 2024-04-22 00:19:03,320 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,320 INFO > Global (OK=10786306 KO=0 ) 2024-04-22 00:19:03,320 INFO > userRestLogin (OK=10786306 KO=0 ) 2024-04-22 00:19:03,320 INFO 2024-04-22 00:19:03,320 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,320 INFO [#########################################################################-] 99% 2024-04-22 00:19:03,320 INFO waiting: 59282 / active: 45 / done: 10786306 2024-04-22 00:19:03,320 INFO ================================================================================ 2024-04-22 00:19:03,320 INFO 2024-04-22 00:19:03,320 INFO 2024-04-22 00:19:03,320 INFO ================================================================================ 2024-04-22 00:19:03,320 INFO 2024-04-22 01:16:47 3615s elapsed 2024-04-22 00:19:03,320 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,320 INFO > Global (OK=10801066 KO=0 ) 2024-04-22 00:19:03,320 INFO > userRestLogin (OK=10801066 KO=0 ) 2024-04-22 00:19:03,320 INFO 2024-04-22 00:19:03,320 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,320 INFO [#########################################################################-] 99% 2024-04-22 00:19:03,320 INFO waiting: 44548 / active: 19 / done: 10801066 2024-04-22 00:19:03,320 INFO ================================================================================ 2024-04-22 00:19:03,320 INFO 2024-04-22 00:19:03,320 INFO 2024-04-22 00:19:03,320 INFO ================================================================================ 2024-04-22 00:19:03,320 INFO 2024-04-22 01:16:52 3620s elapsed 2024-04-22 00:19:03,320 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,320 INFO > Global (OK=10816031 KO=0 ) 2024-04-22 00:19:03,320 INFO > userRestLogin (OK=10816031 KO=0 ) 2024-04-22 00:19:03,320 INFO 2024-04-22 00:19:03,320 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,320 INFO [#########################################################################-] 99% 2024-04-22 00:19:03,321 INFO waiting: 29582 / active: 20 / done: 10816031 2024-04-22 00:19:03,321 INFO ================================================================================ 2024-04-22 00:19:03,321 INFO 2024-04-22 00:19:03,321 INFO 2024-04-22 00:19:03,321 INFO ================================================================================ 2024-04-22 00:19:03,321 INFO 2024-04-22 01:16:57 3625s elapsed 2024-04-22 00:19:03,321 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,321 INFO > Global (OK=10830754 KO=0 ) 2024-04-22 00:19:03,321 INFO > userRestLogin (OK=10830754 KO=0 ) 2024-04-22 00:19:03,321 INFO 2024-04-22 00:19:03,321 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,321 INFO [#########################################################################-] 99% 2024-04-22 00:19:03,321 INFO waiting: 14855 / active: 24 / done: 10830754 2024-04-22 00:19:03,321 INFO ================================================================================ 2024-04-22 00:19:03,321 INFO 2024-04-22 00:19:03,321 INFO 2024-04-22 00:19:03,321 INFO ================================================================================ 2024-04-22 00:19:03,321 INFO 2024-04-22 01:17:02 3630s elapsed 2024-04-22 00:19:03,321 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,321 INFO > Global (OK=10845625 KO=0 ) 2024-04-22 00:19:03,321 INFO > userRestLogin (OK=10845625 KO=0 ) 2024-04-22 00:19:03,321 INFO 2024-04-22 00:19:03,321 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,321 INFO [#########################################################################-] 99% 2024-04-22 00:19:03,321 INFO waiting: 0 / active: 8 / done: 10845625 2024-04-22 00:19:03,321 INFO ================================================================================ 2024-04-22 00:19:03,321 INFO 2024-04-22 00:19:03,321 INFO 2024-04-22 00:19:03,321 INFO ================================================================================ 2024-04-22 00:19:03,321 INFO 2024-04-22 01:17:02 3630s elapsed 2024-04-22 00:19:03,321 INFO ---- Requests ------------------------------------------------------------------ 2024-04-22 00:19:03,321 INFO > Global (OK=10845633 KO=0 ) 2024-04-22 00:19:03,321 INFO > userRestLogin (OK=10845633 KO=0 ) 2024-04-22 00:19:03,321 INFO 2024-04-22 00:19:03,321 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-22 00:19:03,321 INFO [##########################################################################]100% 2024-04-22 00:19:03,321 INFO waiting: 0 / active: 0 / done: 10845633 2024-04-22 00:19:03,321 INFO ================================================================================ 2024-04-22 00:19:03,321 INFO 2024-04-22 00:19:03,321 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-22 00:19:03,322 INFO 2024-04-22 00:19:03,322 INFO ---------------------- 2024-04-22 00:19:03,322 INFO [22/Apr/2024 01:17:02] Create tar file authn-users-3000.tar.gz 2024-04-22 00:19:03,322 INFO ---------------------- 2024-04-22 00:19:03,322 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-22 00:19:03,322 INFO RESULT_FOLDER=authn-users-3000 2024-04-22 00:19:03,322 INFO SIMULATION_PATH=authn-users-3000/loadtest-20240421231631720 2024-04-22 00:19:03,322 INFO PWD=/results/gatling/authn-users-3000/loadtest-20240421231631720 2024-04-22 00:19:03,322 INFO [cmd] tar -czvf /results/gatling//authn-users-3000.tar.gz --exclude=simulation.log * 2024-04-22 00:19:03,322 INFO /results/gatling 2024-04-22 00:19:03,322 INFO 2024-04-22 00:19:03,322 INFO ---------------------- 2024-04-22 00:19:03,322 INFO [22/Apr/2024 01:17:02] Update /results/gatling//latest 2024-04-22 00:19:03,322 INFO ---------------------- 2024-04-22 00:19:03,322 INFO 2024-04-22 00:19:03,322 INFO ---------------------- 2024-04-22 00:19:03,322 INFO [22/Apr/2024 01:17:02] Update /results/gatling//latest.tar.gz 2024-04-22 00:19:03,322 INFO ---------------------- 2024-04-22 00:19:03,322 INFO ________________________________________________________________________________ [2024-04-22 00:19:03] authn-users-3000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-22 00:19:03,322 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-3000/stderr.txt is small (0 lines), printing all lines: 2024-04-22 00:19:03,322 DEBUG ----- output ----- 2024-04-22 00:19:03,322 DEBUG 2024-04-22 00:19:03,322 INFO ________________________________________________________________________________ [2024-04-22 00:19:03] authn-users-3000 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 00:19:03] authn-users-3000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-22 00:19:03] authn-users-3000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped