--Task-- name: authn-users-2000 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': 2000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-11 02:52:52] authn-users-2000 pre : N/A ________________________________________________________________________________ [2024-04-11 02:52:52] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-11 02:52:52] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-2000/stdout.txt Pod log : /results/orders/authn-users-2000 2024-04-11 02:52:52,999 INFO 2024-04-11 02:52:52,999 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-11 02:52:52,999 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:53,311 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:53,311 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:53,311 DEBUG --- stdout --- 2024-04-11 02:52:53,311 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:53,311 DEBUG --- stderr --- 2024-04-11 02:52:53,311 DEBUG ________________________________________________________________________________ [2024-04-11 02:52:53] authn-users-2000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-11 02:52:53,312 INFO 2024-04-11 02:52:53,312 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-11 02:52:53,312 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:53,618 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:53,619 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:53,619 DEBUG --- stdout --- 2024-04-11 02:52:53,619 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:53,619 DEBUG --- stderr --- 2024-04-11 02:52:53,619 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-2000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-11 02:52:53] authn-users-2000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-11 02:52:53,619 INFO 2024-04-11 02:52:53,620 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-11 02:52:53,620 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:53,927 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:53,928 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:53,928 DEBUG --- stdout --- 2024-04-11 02:52:53,928 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:53,928 DEBUG --- stderr --- 2024-04-11 02:52:53,928 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/order.json { "name": "authn-users-2000", "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-2000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-2000", "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=2000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-11 02:52:53] authn-users-2000 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-11 02:52:53,929 INFO 2024-04-11 02:52:53,929 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-11 02:52:53,929 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-11 02:52:54,167 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 02:52:54,168 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:54,168 DEBUG --- stdout --- 2024-04-11 02:52:54,168 DEBUG WAITING 2024-04-11 02:52:54,168 DEBUG --- stderr --- 2024-04-11 02:52:54,168 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-11 02:52:54] authn-users-2000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-11 02:52:54,169 INFO 2024-04-11 02:52:54,169 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-11 02:52:54,169 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:54,487 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:54,487 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:54,487 DEBUG --- stdout --- 2024-04-11 02:52:54,487 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:54,487 DEBUG --- stderr --- 2024-04-11 02:52:54,487 DEBUG 2024-04-11 02:52:54,488 INFO 2024-04-11 02:52:54,488 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-2000/order.json overseer-0-78845f4f8c-v2r2q:/results/orders/order.json 2024-04-11 02:52:54,488 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 02:52:55,875 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:55,875 DEBUG --- stdout --- 2024-04-11 02:52:55,875 DEBUG 2024-04-11 02:52:55,875 DEBUG --- stderr --- 2024-04-11 02:52:55,875 DEBUG Order has been successfully forwarded to overseer [2024-04-11 02:52:55] - INFO: Check if Gatling simulation started successfully 2024-04-11 02:52:55,876 INFO 2024-04-11 02:52:55,876 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-11 02:52:55,876 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 02:52:56,198 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 02:52:56,199 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:52:56,199 DEBUG --- stdout --- 2024-04-11 02:52:56,199 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 02:52:56,199 DEBUG --- stderr --- 2024-04-11 02:52:56,199 DEBUG 2024-04-11 02:52:56,199 INFO 2024-04-11 02:52:56,199 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q -- head -n 25 /results/orders/authn-users-2000/stdout.txt | grep "started..." 2024-04-11 02:52:56,199 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-11 02:52:56,966 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-11 02:53:02,773 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-11 02:53:08,578 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-11 02:53:14,587 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-11 02:53:14,588 INFO [loop_until]: OK (rc = 0) 2024-04-11 02:53:14,588 DEBUG --- stdout --- 2024-04-11 02:53:14,588 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 03:52:59.079 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 03:53:05.045 [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"); ^ 03:53:05.048 [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"); ^ 03:53:07.385 [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-11 02:53:14,588 DEBUG --- stderr --- 2024-04-11 02:53:14,588 DEBUG ________________________________________________________________________________ [2024-04-11 02:53:14] authn-users-2000 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 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-11 03:55:24] authn-users-2000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-11 03:55:24,676 INFO 2024-04-11 03:55:24,676 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-11 03:55:24,676 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:25,016 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:25,016 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:25,017 DEBUG --- stdout --- 2024-04-11 03:55:25,017 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:25,017 DEBUG --- stderr --- 2024-04-11 03:55:25,017 DEBUG 2024-04-11 03:55:25,018 INFO 2024-04-11 03:55:25,018 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q -- rm -f /results/orders/order.json 2024-04-11 03:55:25,018 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 03:55:25,826 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:25,826 DEBUG --- stdout --- 2024-04-11 03:55:25,826 DEBUG 2024-04-11 03:55:25,826 DEBUG --- stderr --- 2024-04-11 03:55:25,826 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-11 03:55:25] authn-users-2000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-11 03:55:25] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/order.json 2024-04-11 03:55:25,827 INFO 2024-04-11 03:55:25,827 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-11 03:55:25,827 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:26,129 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:26,129 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:26,129 DEBUG --- stdout --- 2024-04-11 03:55:26,129 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:26,129 DEBUG --- stderr --- 2024-04-11 03:55:26,129 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 03:55:26,130 INFO 2024-04-11 03:55:26,130 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-11 03:55:26,130 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:26,432 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:26,433 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:26,433 DEBUG --- stdout --- 2024-04-11 03:55:26,433 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:26,433 DEBUG --- stderr --- 2024-04-11 03:55:26,433 DEBUG 2024-04-11 03:55:26,433 INFO 2024-04-11 03:55:26,433 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-2000/rc.txt" ]; then echo "/results/orders/authn-users-2000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-2000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-2000/rc.txt file found" 2024-04-11 03:55:26,433 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 03:55:27,255 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 03:55:27,255 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:27,255 DEBUG --- stdout --- 2024-04-11 03:55:27,255 DEBUG /results/orders/authn-users-2000/rc.txt file found 2024-04-11 03:55:27,255 DEBUG --- stderr --- 2024-04-11 03:55:27,255 DEBUG 2024-04-11 03:55:27,256 INFO 2024-04-11 03:55:27,256 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-11 03:55:27,256 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:27,575 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:27,575 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:27,576 DEBUG --- stdout --- 2024-04-11 03:55:27,576 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:27,576 DEBUG --- stderr --- 2024-04-11 03:55:27,576 DEBUG 2024-04-11 03:55:27,576 INFO 2024-04-11 03:55:27,576 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-2000/rc.txt 2024-04-11 03:55:28,417 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 03:55:28,417 DEBUG --- stdout --- 2024-04-11 03:55:28,418 DEBUG 4 /results/orders/authn-users-2000/rc.txt 2024-04-11 03:55:28,418 DEBUG --- stderr --- 2024-04-11 03:55:28,418 DEBUG 2024-04-11 03:55:28,418 INFO - Download file (size 4k) 2024-04-11 03:55:28,418 INFO 2024-04-11 03:55:28,418 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-11 03:55:28,418 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:28,736 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:28,736 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:28,736 DEBUG --- stdout --- 2024-04-11 03:55:28,736 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:28,736 DEBUG --- stderr --- 2024-04-11 03:55:28,736 DEBUG source : /results/orders/authn-users-2000/rc.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/rc.txt 2024-04-11 03:55:28,736 INFO 2024-04-11 03:55:28,736 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-11 03:55:28,736 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:29,110 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:29,110 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:29,110 DEBUG --- stdout --- 2024-04-11 03:55:29,110 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:29,110 DEBUG --- stderr --- 2024-04-11 03:55:29,110 DEBUG 2024-04-11 03:55:29,111 INFO 2024-04-11 03:55:29,111 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-2000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/rc.txt 2024-04-11 03:55:29,111 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 03:55:29,869 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:29,869 DEBUG --- stdout --- 2024-04-11 03:55:29,869 DEBUG tar: Removing leading `/' from member names 2024-04-11 03:55:29,869 DEBUG --- stderr --- 2024-04-11 03:55:29,869 DEBUG 2024-04-11 03:55:29,870 INFO 2024-04-11 03:55:29,870 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-11 03:55:29,870 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:30,201 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:30,201 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:30,202 DEBUG --- stdout --- 2024-04-11 03:55:30,202 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:30,203 DEBUG --- stderr --- 2024-04-11 03:55:30,203 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 03:55:30,203 INFO 2024-04-11 03:55:30,203 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-11 03:55:30,203 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:30,510 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:30,510 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:30,510 DEBUG --- stdout --- 2024-04-11 03:55:30,510 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:30,510 DEBUG --- stderr --- 2024-04-11 03:55:30,510 DEBUG 2024-04-11 03:55:30,511 INFO 2024-04-11 03:55:30,511 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-2000/stderr.txt" ]; then echo "/results/orders/authn-users-2000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-2000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-2000/stderr.txt file found" 2024-04-11 03:55:30,511 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 03:55:31,342 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 03:55:31,342 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:31,343 DEBUG --- stdout --- 2024-04-11 03:55:31,343 DEBUG /results/orders/authn-users-2000/stderr.txt file found 2024-04-11 03:55:31,343 DEBUG --- stderr --- 2024-04-11 03:55:31,343 DEBUG 2024-04-11 03:55:31,343 INFO 2024-04-11 03:55:31,343 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-11 03:55:31,343 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:31,642 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:31,642 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:31,642 DEBUG --- stdout --- 2024-04-11 03:55:31,642 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:31,642 DEBUG --- stderr --- 2024-04-11 03:55:31,642 DEBUG 2024-04-11 03:55:31,642 INFO 2024-04-11 03:55:31,643 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-2000/stderr.txt 2024-04-11 03:55:32,459 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 03:55:32,459 DEBUG --- stdout --- 2024-04-11 03:55:32,459 DEBUG 0 /results/orders/authn-users-2000/stderr.txt 2024-04-11 03:55:32,460 DEBUG --- stderr --- 2024-04-11 03:55:32,460 DEBUG 2024-04-11 03:55:32,460 INFO - Download file (size 0k) 2024-04-11 03:55:32,460 INFO 2024-04-11 03:55:32,460 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-11 03:55:32,460 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:32,764 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:32,764 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:32,764 DEBUG --- stdout --- 2024-04-11 03:55:32,764 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:32,764 DEBUG --- stderr --- 2024-04-11 03:55:32,764 DEBUG source : /results/orders/authn-users-2000/stderr.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/stderr.txt 2024-04-11 03:55:32,765 INFO 2024-04-11 03:55:32,765 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-11 03:55:32,765 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:33,068 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:33,069 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:33,069 DEBUG --- stdout --- 2024-04-11 03:55:33,069 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:33,069 DEBUG --- stderr --- 2024-04-11 03:55:33,069 DEBUG 2024-04-11 03:55:33,069 INFO 2024-04-11 03:55:33,069 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-2000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/stderr.txt 2024-04-11 03:55:33,069 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 03:55:33,814 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:33,814 DEBUG --- stdout --- 2024-04-11 03:55:33,815 DEBUG tar: Removing leading `/' from member names 2024-04-11 03:55:33,815 DEBUG --- stderr --- 2024-04-11 03:55:33,815 DEBUG 2024-04-11 03:55:33,815 INFO 2024-04-11 03:55:33,815 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-11 03:55:33,815 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:34,116 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:34,117 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:34,117 DEBUG --- stdout --- 2024-04-11 03:55:34,117 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:34,117 DEBUG --- stderr --- 2024-04-11 03:55:34,117 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 03:55:34,117 INFO 2024-04-11 03:55:34,117 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-11 03:55:34,117 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:34,425 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:34,425 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:34,426 DEBUG --- stdout --- 2024-04-11 03:55:34,426 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:34,426 DEBUG --- stderr --- 2024-04-11 03:55:34,426 DEBUG 2024-04-11 03:55:34,426 INFO 2024-04-11 03:55:34,426 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-2000/stdout.txt" ]; then echo "/results/orders/authn-users-2000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-2000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-2000/stdout.txt file found" 2024-04-11 03:55:34,426 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 03:55:35,283 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 03:55:35,290 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:35,291 DEBUG --- stdout --- 2024-04-11 03:55:35,291 DEBUG /results/orders/authn-users-2000/stdout.txt file found 2024-04-11 03:55:35,291 DEBUG --- stderr --- 2024-04-11 03:55:35,292 DEBUG 2024-04-11 03:55:35,292 INFO 2024-04-11 03:55:35,292 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-11 03:55:35,293 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:35,661 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:35,661 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:35,661 DEBUG --- stdout --- 2024-04-11 03:55:35,661 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:35,661 DEBUG --- stderr --- 2024-04-11 03:55:35,661 DEBUG 2024-04-11 03:55:35,661 INFO 2024-04-11 03:55:35,662 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-2000/stdout.txt 2024-04-11 03:55:36,514 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 03:55:36,514 DEBUG --- stdout --- 2024-04-11 03:55:36,515 DEBUG 508 /results/orders/authn-users-2000/stdout.txt 2024-04-11 03:55:36,515 DEBUG --- stderr --- 2024-04-11 03:55:36,515 DEBUG 2024-04-11 03:55:36,515 INFO - Download file (size 508k) 2024-04-11 03:55:36,516 INFO 2024-04-11 03:55:36,516 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-11 03:55:36,516 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:36,872 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:36,872 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:36,872 DEBUG --- stdout --- 2024-04-11 03:55:36,872 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:36,872 DEBUG --- stderr --- 2024-04-11 03:55:36,872 DEBUG source : /results/orders/authn-users-2000/stdout.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/stdout.txt 2024-04-11 03:55:36,873 INFO 2024-04-11 03:55:36,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-11 03:55:36,873 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:37,204 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:37,204 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:37,204 DEBUG --- stdout --- 2024-04-11 03:55:37,204 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:37,204 DEBUG --- stderr --- 2024-04-11 03:55:37,204 DEBUG 2024-04-11 03:55:37,204 INFO 2024-04-11 03:55:37,204 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-2000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/stdout.txt 2024-04-11 03:55:37,205 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 03:55:38,273 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:38,273 DEBUG --- stdout --- 2024-04-11 03:55:38,273 DEBUG tar: Removing leading `/' from member names 2024-04-11 03:55:38,273 DEBUG --- stderr --- 2024-04-11 03:55:38,273 DEBUG ________________________________________________________________________________ [2024-04-11 03:55:38] authn-users-2000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-11 03:55:38,273 INFO 2024-04-11 03:55:38,273 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-11 03:55:38,274 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:38,590 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:38,590 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:38,591 DEBUG --- stdout --- 2024-04-11 03:55:38,591 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:38,591 DEBUG --- stderr --- 2024-04-11 03:55:38,591 DEBUG Checking pod: overseer-0-78845f4f8c-v2r2q for file exists, if not wait. 2024-04-11 03:55:38,591 INFO 2024-04-11 03:55:38,591 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-11 03:55:38,591 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:38,911 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:38,911 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:38,911 DEBUG --- stdout --- 2024-04-11 03:55:38,912 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:38,912 DEBUG --- stderr --- 2024-04-11 03:55:38,912 DEBUG 2024-04-11 03:55:38,912 INFO 2024-04-11 03:55:38,912 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-2000/cmd.txt" ]; then echo "/results/orders/authn-users-2000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-2000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-2000/cmd.txt file found" 2024-04-11 03:55:38,912 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-11 03:55:39,752 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-11 03:55:39,752 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:39,752 DEBUG --- stdout --- 2024-04-11 03:55:39,753 DEBUG /results/orders/authn-users-2000/cmd.txt file found 2024-04-11 03:55:39,753 DEBUG --- stderr --- 2024-04-11 03:55:39,753 DEBUG 2024-04-11 03:55:39,753 INFO 2024-04-11 03:55:39,753 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-11 03:55:39,753 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:40,074 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:40,074 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:40,074 DEBUG --- stdout --- 2024-04-11 03:55:40,074 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:40,075 DEBUG --- stderr --- 2024-04-11 03:55:40,075 DEBUG 2024-04-11 03:55:40,075 INFO 2024-04-11 03:55:40,075 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-78845f4f8c-v2r2q --stdin -- du -k /results/orders/authn-users-2000/cmd.txt 2024-04-11 03:55:40,903 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-11 03:55:40,903 DEBUG --- stdout --- 2024-04-11 03:55:40,903 DEBUG 4 /results/orders/authn-users-2000/cmd.txt 2024-04-11 03:55:40,903 DEBUG --- stderr --- 2024-04-11 03:55:40,903 DEBUG 2024-04-11 03:55:40,903 INFO - Download file (size 4k) 2024-04-11 03:55:40,904 INFO 2024-04-11 03:55:40,904 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-11 03:55:40,904 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:41,216 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:41,216 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:41,216 DEBUG --- stdout --- 2024-04-11 03:55:41,216 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:41,216 DEBUG --- stderr --- 2024-04-11 03:55:41,216 DEBUG source : /results/orders/authn-users-2000/cmd.txt @ overseer-0-78845f4f8c-v2r2q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/cmd.txt 2024-04-11 03:55:41,217 INFO 2024-04-11 03:55:41,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-11 03:55:41,217 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-11 03:55:41,584 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-11 03:55:41,584 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:41,584 DEBUG --- stdout --- 2024-04-11 03:55:41,584 DEBUG overseer-0-78845f4f8c-v2r2q 2024-04-11 03:55:41,584 DEBUG --- stderr --- 2024-04-11 03:55:41,585 DEBUG 2024-04-11 03:55:41,585 INFO 2024-04-11 03:55:41,585 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-78845f4f8c-v2r2q:/results/orders/authn-users-2000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/cmd.txt 2024-04-11 03:55:41,585 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-11 03:55:42,418 INFO [loop_until]: OK (rc = 0) 2024-04-11 03:55:42,418 DEBUG --- stdout --- 2024-04-11 03:55:42,418 DEBUG tar: Removing leading `/' from member names 2024-04-11 03:55:42,419 DEBUG --- stderr --- 2024-04-11 03:55:42,419 DEBUG 2024-04-11 03:55:42,419 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/cmd.txt is small (1 lines), printing all lines: 2024-04-11 03:55:42,419 DEBUG ----- output ----- 2024-04-11 03:55:42,419 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-2000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-11 03:55:42,419 INFO ________________________________________________________________________________ [2024-04-11 03:55:42] authn-users-2000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-11 03:55:42,420 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/rc.txt is small (1 lines), printing all lines: 2024-04-11 03:55:42,420 DEBUG ----- output ----- 2024-04-11 03:55:42,420 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-11 03:55:42,420 INFO ________________________________________________________________________________ [2024-04-11 03:55:42] authn-users-2000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-11 03:55:42,421 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-2000/stdout.txt is 8754 lines): 2024-04-11 03:55:42,421 DEBUG ----- output ----- 2024-04-11 03:55:42,422 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-11 03:55:42,422 INFO 03:52:59.079 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-11 03:55:42,422 INFO 03:53:05.045 [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-11 03:55:42,422 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-11 03:55:42,422 INFO ^ 2024-04-11 03:55:42,422 INFO 03:53:05.048 [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-11 03:55:42,422 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-11 03:55:42,422 INFO ^ 2024-04-11 03:55:42,422 INFO 03:53:07.385 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-11 03:55:42,422 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-11 03:55:42,422 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-11 03:55:42,422 INFO 2024-04-11 03:55:42,422 INFO ================================================================================ 2024-04-11 03:55:42,422 INFO 2024-04-11 03:53:16 5s elapsed 2024-04-11 03:55:42,422 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,422 INFO > Global (OK=833 KO=0 ) 2024-04-11 03:55:42,422 INFO > userRestLogin (OK=833 KO=0 ) 2024-04-11 03:55:42,422 INFO 2024-04-11 03:55:42,422 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,422 INFO [- ] 0% 2024-04-11 03:55:42,422 INFO waiting: 7222909 / active: 4 / done: 833 2024-04-11 03:55:42,422 INFO ================================================================================ 2024-04-11 03:55:42,422 INFO 2024-04-11 03:55:42,422 INFO 2024-04-11 03:55:42,422 INFO ================================================================================ 2024-04-11 03:55:42,422 INFO 2024-04-11 03:53:21 10s elapsed 2024-04-11 03:55:42,422 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,422 INFO > Global (OK=3334 KO=0 ) 2024-04-11 03:55:42,422 INFO > userRestLogin (OK=3334 KO=0 ) 2024-04-11 03:55:42,423 INFO 2024-04-11 03:55:42,423 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,423 INFO [- ] 0% 2024-04-11 03:55:42,423 INFO waiting: 7220406 / active: 6 / done: 3334 2024-04-11 03:55:42,423 INFO ================================================================================ 2024-04-11 03:55:42,423 INFO 2024-04-11 03:55:42,423 INFO 2024-04-11 03:55:42,423 INFO ================================================================================ 2024-04-11 03:55:42,423 INFO 2024-04-11 03:53:26 15s elapsed 2024-04-11 03:55:42,423 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,423 INFO > Global (OK=7501 KO=0 ) 2024-04-11 03:55:42,423 INFO > userRestLogin (OK=7501 KO=0 ) 2024-04-11 03:55:42,423 INFO 2024-04-11 03:55:42,423 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,423 INFO [- ] 0% 2024-04-11 03:55:42,423 INFO waiting: 7216234 / active: 11 / done: 7501 2024-04-11 03:55:42,423 INFO ================================================================================ 2024-04-11 03:55:42,423 INFO 2024-04-11 03:55:42,423 INFO 2024-04-11 03:55:42,423 INFO ================================================================================ 2024-04-11 03:55:42,423 INFO 2024-04-11 03:53:31 20s elapsed 2024-04-11 03:55:42,423 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,423 INFO > Global (OK=13333 KO=0 ) 2024-04-11 03:55:42,423 INFO > userRestLogin (OK=13333 KO=0 ) 2024-04-11 03:55:42,423 INFO 2024-04-11 03:55:42,423 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,423 INFO [- ] 0% 2024-04-11 03:55:42,423 INFO waiting: 7210399 / active: 14 / done: 13333 2024-04-11 03:55:42,423 INFO ================================================================================ 2024-04-11 03:55:42,423 INFO 2024-04-11 03:55:42,423 INFO 2024-04-11 03:55:42,423 INFO ================================================================================ 2024-04-11 03:55:42,424 INFO 2024-04-11 03:53:36 25s elapsed 2024-04-11 03:55:42,424 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,424 INFO > Global (OK=20833 KO=0 ) 2024-04-11 03:55:42,424 INFO > userRestLogin (OK=20833 KO=0 ) 2024-04-11 03:55:42,424 INFO 2024-04-11 03:55:42,424 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,424 INFO [- ] 0% 2024-04-11 03:55:42,424 INFO waiting: 7202894 / active: 19 / done: 20833 2024-04-11 03:55:42,424 INFO ================================================================================ 2024-04-11 03:55:42,424 INFO 2024-04-11 03:55:42,424 INFO 2024-04-11 03:55:42,424 INFO ================================================================================ 2024-04-11 03:55:42,424 INFO 2024-04-11 03:53:41 30s elapsed 2024-04-11 03:55:42,424 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,424 INFO > Global (OK=29928 KO=0 ) 2024-04-11 03:55:42,424 INFO > userRestLogin (OK=29928 KO=0 ) 2024-04-11 03:55:42,424 INFO 2024-04-11 03:55:42,424 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,424 INFO [- ] 0% 2024-04-11 03:55:42,424 INFO waiting: 7193734 / active: 84 / done: 29928 2024-04-11 03:55:42,424 INFO ================================================================================ 2024-04-11 03:55:42,424 INFO 2024-04-11 03:55:42,424 INFO 2024-04-11 03:55:42,424 INFO ================================================================================ 2024-04-11 03:55:42,424 INFO 2024-04-11 03:53:46 35s elapsed 2024-04-11 03:55:42,424 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,424 INFO > Global (OK=40055 KO=0 ) 2024-04-11 03:55:42,424 INFO > userRestLogin (OK=40055 KO=0 ) 2024-04-11 03:55:42,424 INFO 2024-04-11 03:55:42,424 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,424 INFO [- ] 0% 2024-04-11 03:55:42,424 INFO waiting: 7183676 / active: 16 / done: 40054 2024-04-11 03:55:42,425 INFO ================================================================================ 2024-04-11 03:55:42,425 INFO 2024-04-11 03:55:42,425 INFO 2024-04-11 03:55:42,425 INFO ================================================================================ 2024-04-11 03:55:42,425 INFO 2024-04-11 03:53:51 40s elapsed 2024-04-11 03:55:42,425 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,425 INFO > Global (OK=49832 KO=0 ) 2024-04-11 03:55:42,425 INFO [...] 2024-04-11 03:55:42,429 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,429 INFO > Global (OK=7173570 KO=0 ) 2024-04-11 03:55:42,429 INFO > userRestLogin (OK=7173570 KO=0 ) 2024-04-11 03:55:42,429 INFO 2024-04-11 03:55:42,429 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,429 INFO [#########################################################################-] 99% 2024-04-11 03:55:42,429 INFO waiting: 50152 / active: 24 / done: 7173570 2024-04-11 03:55:42,429 INFO ================================================================================ 2024-04-11 03:55:42,429 INFO 2024-04-11 03:55:42,429 INFO 2024-04-11 03:55:42,429 INFO ================================================================================ 2024-04-11 03:55:42,430 INFO 2024-04-11 04:53:21 3610s elapsed 2024-04-11 03:55:42,430 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,430 INFO > Global (OK=7183667 KO=0 ) 2024-04-11 03:55:42,430 INFO > userRestLogin (OK=7183667 KO=0 ) 2024-04-11 03:55:42,430 INFO 2024-04-11 03:55:42,430 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,430 INFO [#########################################################################-] 99% 2024-04-11 03:55:42,430 INFO waiting: 40055 / active: 24 / done: 7183667 2024-04-11 03:55:42,430 INFO ================================================================================ 2024-04-11 03:55:42,430 INFO 2024-04-11 03:55:42,430 INFO 2024-04-11 03:55:42,430 INFO ================================================================================ 2024-04-11 03:55:42,430 INFO 2024-04-11 04:53:26 3615s elapsed 2024-04-11 03:55:42,430 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,431 INFO > Global (OK=7193620 KO=0 ) 2024-04-11 03:55:42,431 INFO > userRestLogin (OK=7193620 KO=0 ) 2024-04-11 03:55:42,431 INFO 2024-04-11 03:55:42,431 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,431 INFO [#########################################################################-] 99% 2024-04-11 03:55:42,431 INFO waiting: 30109 / active: 17 / done: 7193620 2024-04-11 03:55:42,431 INFO ================================================================================ 2024-04-11 03:55:42,431 INFO 2024-04-11 03:55:42,431 INFO 2024-04-11 03:55:42,431 INFO ================================================================================ 2024-04-11 03:55:42,431 INFO 2024-04-11 04:53:31 3620s elapsed 2024-04-11 03:55:42,431 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,431 INFO > Global (OK=7203717 KO=0 ) 2024-04-11 03:55:42,431 INFO > userRestLogin (OK=7203717 KO=0 ) 2024-04-11 03:55:42,431 INFO 2024-04-11 03:55:42,431 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,432 INFO [#########################################################################-] 99% 2024-04-11 03:55:42,432 INFO waiting: 20007 / active: 23 / done: 7203716 2024-04-11 03:55:42,432 INFO ================================================================================ 2024-04-11 03:55:42,432 INFO 2024-04-11 03:55:42,432 INFO 2024-04-11 03:55:42,432 INFO ================================================================================ 2024-04-11 03:55:42,432 INFO 2024-04-11 04:53:36 3625s elapsed 2024-04-11 03:55:42,432 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,432 INFO > Global (OK=7213703 KO=0 ) 2024-04-11 03:55:42,432 INFO > userRestLogin (OK=7213703 KO=0 ) 2024-04-11 03:55:42,432 INFO 2024-04-11 03:55:42,432 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,432 INFO [#########################################################################-] 99% 2024-04-11 03:55:42,432 INFO waiting: 10015 / active: 28 / done: 7213703 2024-04-11 03:55:42,432 INFO ================================================================================ 2024-04-11 03:55:42,432 INFO 2024-04-11 03:55:42,432 INFO 2024-04-11 03:55:42,432 INFO ================================================================================ 2024-04-11 03:55:42,432 INFO 2024-04-11 04:53:41 3630s elapsed 2024-04-11 03:55:42,432 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,432 INFO > Global (OK=7223712 KO=0 ) 2024-04-11 03:55:42,432 INFO > userRestLogin (OK=7223712 KO=0 ) 2024-04-11 03:55:42,432 INFO 2024-04-11 03:55:42,432 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,433 INFO [#########################################################################-] 99% 2024-04-11 03:55:42,433 INFO waiting: 0 / active: 34 / done: 7223712 2024-04-11 03:55:42,433 INFO ================================================================================ 2024-04-11 03:55:42,433 INFO 2024-04-11 03:55:42,433 INFO 2024-04-11 03:55:42,433 INFO ================================================================================ 2024-04-11 03:55:42,433 INFO 2024-04-11 04:53:41 3630s elapsed 2024-04-11 03:55:42,433 INFO ---- Requests ------------------------------------------------------------------ 2024-04-11 03:55:42,433 INFO > Global (OK=7223746 KO=0 ) 2024-04-11 03:55:42,433 INFO > userRestLogin (OK=7223746 KO=0 ) 2024-04-11 03:55:42,433 INFO 2024-04-11 03:55:42,433 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-11 03:55:42,433 INFO [##########################################################################]100% 2024-04-11 03:55:42,433 INFO waiting: 0 / active: 0 / done: 7223746 2024-04-11 03:55:42,433 INFO ================================================================================ 2024-04-11 03:55:42,433 INFO 2024-04-11 03:55:42,433 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-11 03:55:42,433 INFO 2024-04-11 03:55:42,433 INFO ---------------------- 2024-04-11 03:55:42,433 INFO [11/Apr/2024 04:53:41] Create tar file authn-users-2000.tar.gz 2024-04-11 03:55:42,433 INFO ---------------------- 2024-04-11 03:55:42,433 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-11 03:55:42,433 INFO RESULT_FOLDER=authn-users-2000 2024-04-11 03:55:42,434 INFO SIMULATION_PATH=authn-users-2000/loadtest-20240411025311215 2024-04-11 03:55:42,434 INFO PWD=/results/gatling/authn-users-2000/loadtest-20240411025311215 2024-04-11 03:55:42,434 INFO [cmd] tar -czvf /results/gatling//authn-users-2000.tar.gz --exclude=simulation.log * 2024-04-11 03:55:42,434 INFO /results/gatling 2024-04-11 03:55:42,434 INFO 2024-04-11 03:55:42,434 INFO ---------------------- 2024-04-11 03:55:42,434 INFO [11/Apr/2024 04:53:41] Update /results/gatling//latest 2024-04-11 03:55:42,434 INFO ---------------------- 2024-04-11 03:55:42,434 INFO 2024-04-11 03:55:42,434 INFO ---------------------- 2024-04-11 03:55:42,434 INFO [11/Apr/2024 04:53:41] Update /results/gatling//latest.tar.gz 2024-04-11 03:55:42,434 INFO ---------------------- 2024-04-11 03:55:42,434 INFO ________________________________________________________________________________ [2024-04-11 03:55:42] authn-users-2000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-11 03:55:42,435 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-2000/stderr.txt is small (0 lines), printing all lines: 2024-04-11 03:55:42,435 DEBUG ----- output ----- 2024-04-11 03:55:42,435 DEBUG 2024-04-11 03:55:42,435 INFO ________________________________________________________________________________ [2024-04-11 03:55:42] authn-users-2000 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-11 03:55:42] authn-users-2000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-11 03:55:42] authn-users-2000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped