--Task-- name: authn-users-1000 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': 1000, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-14 18:38:32] authn-users-1000 pre : N/A ________________________________________________________________________________ [2024-04-14 18:38:32] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-14 18:38:32] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-1000/stdout.txt Pod log : /results/orders/authn-users-1000 2024-04-14 18:38:32,883 INFO 2024-04-14 18:38:32,883 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 18:38:32,883 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:33,216 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:33,216 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:33,216 DEBUG --- stdout --- 2024-04-14 18:38:33,216 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:33,216 DEBUG --- stderr --- 2024-04-14 18:38:33,216 DEBUG ________________________________________________________________________________ [2024-04-14 18:38:33] authn-users-1000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-14 18:38:33,216 INFO 2024-04-14 18:38:33,216 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 18:38:33,217 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:33,551 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:33,552 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:33,552 DEBUG --- stdout --- 2024-04-14 18:38:33,552 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:33,552 DEBUG --- stderr --- 2024-04-14 18:38:33,552 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-1000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-14 18:38:33] authn-users-1000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-14 18:38:33,552 INFO 2024-04-14 18:38:33,553 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 18:38:33,553 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:33,921 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:33,922 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:33,922 DEBUG --- stdout --- 2024-04-14 18:38:33,922 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:33,922 DEBUG --- stderr --- 2024-04-14 18:38:33,922 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/order.json { "name": "authn-users-1000", "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-1000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-1000", "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=1000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-14 18:38:33] authn-users-1000 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-04-14 18:38:33,924 INFO 2024-04-14 18:38:33,924 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-14 18:38:33,924 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 18:38:34,168 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 18:38:34,168 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:34,168 DEBUG --- stdout --- 2024-04-14 18:38:34,168 DEBUG WAITING 2024-04-14 18:38:34,168 DEBUG --- stderr --- 2024-04-14 18:38:34,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 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-14 18:38:34] authn-users-1000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-14 18:38:34,169 INFO 2024-04-14 18:38:34,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-14 18:38:34,169 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:34,480 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:34,480 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:34,480 DEBUG --- stdout --- 2024-04-14 18:38:34,480 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:34,480 DEBUG --- stderr --- 2024-04-14 18:38:34,480 DEBUG 2024-04-14 18:38:34,481 INFO 2024-04-14 18:38:34,481 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-1000/order.json overseer-0-5584f6c4db-bcn5q:/results/orders/order.json 2024-04-14 18:38:34,481 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 18:38:35,918 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:35,918 DEBUG --- stdout --- 2024-04-14 18:38:35,918 DEBUG 2024-04-14 18:38:35,918 DEBUG --- stderr --- 2024-04-14 18:38:35,918 DEBUG Order has been successfully forwarded to overseer [2024-04-14 18:38:35] - INFO: Check if Gatling simulation started successfully 2024-04-14 18:38:35,919 INFO 2024-04-14 18:38:35,919 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 18:38:35,919 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:36,236 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:36,236 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:36,236 DEBUG --- stdout --- 2024-04-14 18:38:36,236 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:36,236 DEBUG --- stderr --- 2024-04-14 18:38:36,236 DEBUG 2024-04-14 18:38:36,236 INFO 2024-04-14 18:38:36,236 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- head -n 25 /results/orders/authn-users-1000/stdout.txt | grep "started..." 2024-04-14 18:38:36,236 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 18:38:36,984 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-14 18:38:42,752 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-14 18:38:48,530 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-14 18:38:54,281 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-14 18:38:54,282 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:54,282 DEBUG --- stdout --- 2024-04-14 18:38:54,282 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 19:38:39.482 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 19:38:46.865 [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"); ^ 19:38:46.869 [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"); ^ 19:38:49.564 [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-14 18:38:54,282 DEBUG --- stderr --- 2024-04-14 18:38:54,282 DEBUG ________________________________________________________________________________ [2024-04-14 18:38:54] authn-users-1000 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, 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 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-14 19:41:04] authn-users-1000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-14 19:41:04,936 INFO 2024-04-14 19:41:04,936 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:04,936 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:05,267 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:05,267 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:05,267 DEBUG --- stdout --- 2024-04-14 19:41:05,267 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:05,267 DEBUG --- stderr --- 2024-04-14 19:41:05,267 DEBUG 2024-04-14 19:41:05,268 INFO 2024-04-14 19:41:05,268 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- rm -f /results/orders/order.json 2024-04-14 19:41:05,268 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 19:41:06,070 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:06,070 DEBUG --- stdout --- 2024-04-14 19:41:06,070 DEBUG 2024-04-14 19:41:06,070 DEBUG --- stderr --- 2024-04-14 19:41:06,070 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-14 19:41:06] authn-users-1000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-14 19:41:06] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/order.json 2024-04-14 19:41:06,071 INFO 2024-04-14 19:41:06,071 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:06,071 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:06,408 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:06,409 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:06,409 DEBUG --- stdout --- 2024-04-14 19:41:06,409 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:06,409 DEBUG --- stderr --- 2024-04-14 19:41:06,409 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 19:41:06,410 INFO 2024-04-14 19:41:06,410 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:06,410 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:06,758 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:06,759 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:06,759 DEBUG --- stdout --- 2024-04-14 19:41:06,759 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:06,759 DEBUG --- stderr --- 2024-04-14 19:41:06,759 DEBUG 2024-04-14 19:41:06,759 INFO 2024-04-14 19:41:06,759 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-1000/rc.txt" ]; then echo "/results/orders/authn-users-1000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-1000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-1000/rc.txt file found" 2024-04-14 19:41:06,759 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 19:41:07,614 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 19:41:07,614 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:07,614 DEBUG --- stdout --- 2024-04-14 19:41:07,614 DEBUG /results/orders/authn-users-1000/rc.txt file found 2024-04-14 19:41:07,614 DEBUG --- stderr --- 2024-04-14 19:41:07,614 DEBUG 2024-04-14 19:41:07,614 INFO 2024-04-14 19:41:07,614 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:07,614 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:07,916 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:07,916 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:07,916 DEBUG --- stdout --- 2024-04-14 19:41:07,916 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:07,916 DEBUG --- stderr --- 2024-04-14 19:41:07,916 DEBUG 2024-04-14 19:41:07,916 INFO 2024-04-14 19:41:07,916 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-1000/rc.txt 2024-04-14 19:41:08,734 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 19:41:08,734 DEBUG --- stdout --- 2024-04-14 19:41:08,734 DEBUG 4 /results/orders/authn-users-1000/rc.txt 2024-04-14 19:41:08,734 DEBUG --- stderr --- 2024-04-14 19:41:08,734 DEBUG 2024-04-14 19:41:08,734 INFO - Download file (size 4k) 2024-04-14 19:41:08,735 INFO 2024-04-14 19:41:08,735 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:08,735 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:09,045 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:09,046 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:09,046 DEBUG --- stdout --- 2024-04-14 19:41:09,046 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:09,046 DEBUG --- stderr --- 2024-04-14 19:41:09,046 DEBUG source : /results/orders/authn-users-1000/rc.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/rc.txt 2024-04-14 19:41:09,046 INFO 2024-04-14 19:41:09,046 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:09,046 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:09,353 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:09,353 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:09,353 DEBUG --- stdout --- 2024-04-14 19:41:09,353 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:09,353 DEBUG --- stderr --- 2024-04-14 19:41:09,353 DEBUG 2024-04-14 19:41:09,354 INFO 2024-04-14 19:41:09,354 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-1000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/rc.txt 2024-04-14 19:41:09,354 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 19:41:10,150 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:10,150 DEBUG --- stdout --- 2024-04-14 19:41:10,151 DEBUG tar: Removing leading `/' from member names 2024-04-14 19:41:10,151 DEBUG --- stderr --- 2024-04-14 19:41:10,151 DEBUG 2024-04-14 19:41:10,152 INFO 2024-04-14 19:41:10,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-14 19:41:10,152 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:10,550 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:10,550 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:10,550 DEBUG --- stdout --- 2024-04-14 19:41:10,550 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:10,550 DEBUG --- stderr --- 2024-04-14 19:41:10,550 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 19:41:10,550 INFO 2024-04-14 19:41:10,550 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:10,551 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:10,926 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:10,927 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:10,927 DEBUG --- stdout --- 2024-04-14 19:41:10,927 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:10,927 DEBUG --- stderr --- 2024-04-14 19:41:10,927 DEBUG 2024-04-14 19:41:10,927 INFO 2024-04-14 19:41:10,927 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-1000/stderr.txt" ]; then echo "/results/orders/authn-users-1000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-1000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-1000/stderr.txt file found" 2024-04-14 19:41:10,927 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 19:41:11,786 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 19:41:11,786 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:11,787 DEBUG --- stdout --- 2024-04-14 19:41:11,787 DEBUG /results/orders/authn-users-1000/stderr.txt file found 2024-04-14 19:41:11,787 DEBUG --- stderr --- 2024-04-14 19:41:11,787 DEBUG 2024-04-14 19:41:11,787 INFO 2024-04-14 19:41:11,787 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:11,787 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:12,156 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:12,156 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:12,156 DEBUG --- stdout --- 2024-04-14 19:41:12,156 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:12,156 DEBUG --- stderr --- 2024-04-14 19:41:12,156 DEBUG 2024-04-14 19:41:12,157 INFO 2024-04-14 19:41:12,157 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-1000/stderr.txt 2024-04-14 19:41:13,028 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 19:41:13,028 DEBUG --- stdout --- 2024-04-14 19:41:13,028 DEBUG 0 /results/orders/authn-users-1000/stderr.txt 2024-04-14 19:41:13,028 DEBUG --- stderr --- 2024-04-14 19:41:13,028 DEBUG 2024-04-14 19:41:13,028 INFO - Download file (size 0k) 2024-04-14 19:41:13,029 INFO 2024-04-14 19:41:13,029 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:13,029 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:13,350 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:13,351 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:13,351 DEBUG --- stdout --- 2024-04-14 19:41:13,351 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:13,351 DEBUG --- stderr --- 2024-04-14 19:41:13,351 DEBUG source : /results/orders/authn-users-1000/stderr.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/stderr.txt 2024-04-14 19:41:13,351 INFO 2024-04-14 19:41:13,351 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:13,351 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:13,659 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:13,659 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:13,659 DEBUG --- stdout --- 2024-04-14 19:41:13,659 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:13,659 DEBUG --- stderr --- 2024-04-14 19:41:13,659 DEBUG 2024-04-14 19:41:13,660 INFO 2024-04-14 19:41:13,660 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-1000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/stderr.txt 2024-04-14 19:41:13,660 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 19:41:14,422 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:14,423 DEBUG --- stdout --- 2024-04-14 19:41:14,423 DEBUG tar: Removing leading `/' from member names 2024-04-14 19:41:14,423 DEBUG --- stderr --- 2024-04-14 19:41:14,423 DEBUG 2024-04-14 19:41:14,423 INFO 2024-04-14 19:41:14,423 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:14,423 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:14,776 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:14,776 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:14,776 DEBUG --- stdout --- 2024-04-14 19:41:14,776 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:14,776 DEBUG --- stderr --- 2024-04-14 19:41:14,776 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 19:41:14,777 INFO 2024-04-14 19:41:14,777 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:14,777 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:15,086 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:15,086 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:15,086 DEBUG --- stdout --- 2024-04-14 19:41:15,086 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:15,086 DEBUG --- stderr --- 2024-04-14 19:41:15,086 DEBUG 2024-04-14 19:41:15,087 INFO 2024-04-14 19:41:15,087 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-1000/stdout.txt" ]; then echo "/results/orders/authn-users-1000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-1000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-1000/stdout.txt file found" 2024-04-14 19:41:15,087 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 19:41:15,942 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 19:41:15,943 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:15,943 DEBUG --- stdout --- 2024-04-14 19:41:15,943 DEBUG /results/orders/authn-users-1000/stdout.txt file found 2024-04-14 19:41:15,943 DEBUG --- stderr --- 2024-04-14 19:41:15,943 DEBUG 2024-04-14 19:41:15,943 INFO 2024-04-14 19:41:15,943 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:15,943 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:16,275 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:16,275 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:16,275 DEBUG --- stdout --- 2024-04-14 19:41:16,275 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:16,275 DEBUG --- stderr --- 2024-04-14 19:41:16,275 DEBUG 2024-04-14 19:41:16,276 INFO 2024-04-14 19:41:16,276 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-1000/stdout.txt 2024-04-14 19:41:17,124 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 19:41:17,126 DEBUG --- stdout --- 2024-04-14 19:41:17,126 DEBUG 508 /results/orders/authn-users-1000/stdout.txt 2024-04-14 19:41:17,127 DEBUG --- stderr --- 2024-04-14 19:41:17,127 DEBUG 2024-04-14 19:41:17,127 INFO - Download file (size 508k) 2024-04-14 19:41:17,127 INFO 2024-04-14 19:41:17,127 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:17,127 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:17,447 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:17,448 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:17,448 DEBUG --- stdout --- 2024-04-14 19:41:17,448 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:17,448 DEBUG --- stderr --- 2024-04-14 19:41:17,448 DEBUG source : /results/orders/authn-users-1000/stdout.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/stdout.txt 2024-04-14 19:41:17,449 INFO 2024-04-14 19:41:17,449 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:17,449 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:17,773 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:17,774 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:17,774 DEBUG --- stdout --- 2024-04-14 19:41:17,774 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:17,774 DEBUG --- stderr --- 2024-04-14 19:41:17,774 DEBUG 2024-04-14 19:41:17,775 INFO 2024-04-14 19:41:17,775 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-1000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/stdout.txt 2024-04-14 19:41:17,775 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 19:41:18,915 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:18,915 DEBUG --- stdout --- 2024-04-14 19:41:18,915 DEBUG tar: Removing leading `/' from member names 2024-04-14 19:41:18,915 DEBUG --- stderr --- 2024-04-14 19:41:18,915 DEBUG ________________________________________________________________________________ [2024-04-14 19:41:18] authn-users-1000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-14 19:41:18,916 INFO 2024-04-14 19:41:18,916 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:18,916 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:19,239 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:19,239 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:19,239 DEBUG --- stdout --- 2024-04-14 19:41:19,239 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:19,239 DEBUG --- stderr --- 2024-04-14 19:41:19,239 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 19:41:19,240 INFO 2024-04-14 19:41:19,240 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:19,240 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:19,545 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:19,545 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:19,545 DEBUG --- stdout --- 2024-04-14 19:41:19,546 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:19,546 DEBUG --- stderr --- 2024-04-14 19:41:19,546 DEBUG 2024-04-14 19:41:19,546 INFO 2024-04-14 19:41:19,546 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-1000/cmd.txt" ]; then echo "/results/orders/authn-users-1000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-1000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-1000/cmd.txt file found" 2024-04-14 19:41:19,546 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 19:41:20,399 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 19:41:20,400 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:20,400 DEBUG --- stdout --- 2024-04-14 19:41:20,400 DEBUG /results/orders/authn-users-1000/cmd.txt file found 2024-04-14 19:41:20,400 DEBUG --- stderr --- 2024-04-14 19:41:20,400 DEBUG 2024-04-14 19:41:20,400 INFO 2024-04-14 19:41:20,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-14 19:41:20,400 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:20,709 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:20,709 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:20,709 DEBUG --- stdout --- 2024-04-14 19:41:20,709 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:20,709 DEBUG --- stderr --- 2024-04-14 19:41:20,709 DEBUG 2024-04-14 19:41:20,710 INFO 2024-04-14 19:41:20,710 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-1000/cmd.txt 2024-04-14 19:41:21,536 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 19:41:21,536 DEBUG --- stdout --- 2024-04-14 19:41:21,536 DEBUG 4 /results/orders/authn-users-1000/cmd.txt 2024-04-14 19:41:21,536 DEBUG --- stderr --- 2024-04-14 19:41:21,536 DEBUG 2024-04-14 19:41:21,536 INFO - Download file (size 4k) 2024-04-14 19:41:21,537 INFO 2024-04-14 19:41:21,537 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:21,537 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:21,870 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:21,870 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:21,871 DEBUG --- stdout --- 2024-04-14 19:41:21,871 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:21,871 DEBUG --- stderr --- 2024-04-14 19:41:21,871 DEBUG source : /results/orders/authn-users-1000/cmd.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/cmd.txt 2024-04-14 19:41:21,871 INFO 2024-04-14 19:41:21,871 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-14 19:41:21,871 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 19:41:22,207 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 19:41:22,209 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:22,209 DEBUG --- stdout --- 2024-04-14 19:41:22,209 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 19:41:22,209 DEBUG --- stderr --- 2024-04-14 19:41:22,209 DEBUG 2024-04-14 19:41:22,210 INFO 2024-04-14 19:41:22,210 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-1000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/cmd.txt 2024-04-14 19:41:22,210 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 19:41:23,023 INFO [loop_until]: OK (rc = 0) 2024-04-14 19:41:23,023 DEBUG --- stdout --- 2024-04-14 19:41:23,023 DEBUG tar: Removing leading `/' from member names 2024-04-14 19:41:23,023 DEBUG --- stderr --- 2024-04-14 19:41:23,023 DEBUG 2024-04-14 19:41:23,024 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/cmd.txt is small (1 lines), printing all lines: 2024-04-14 19:41:23,024 DEBUG ----- output ----- 2024-04-14 19:41:23,024 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-1000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-14 19:41:23,024 INFO ________________________________________________________________________________ [2024-04-14 19:41:23] authn-users-1000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-14 19:41:23,024 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/rc.txt is small (1 lines), printing all lines: 2024-04-14 19:41:23,024 DEBUG ----- output ----- 2024-04-14 19:41:23,024 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-14 19:41:23,024 INFO ________________________________________________________________________________ [2024-04-14 19:41:23] authn-users-1000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-14 19:41:23,026 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-1000/stdout.txt is 8754 lines): 2024-04-14 19:41:23,026 DEBUG ----- output ----- 2024-04-14 19:41:23,026 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-14 19:41:23,026 INFO 19:38:39.482 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-14 19:41:23,026 INFO 19:38:46.865 [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-14 19:41:23,026 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-14 19:41:23,026 INFO ^ 2024-04-14 19:41:23,026 INFO 19:38:46.869 [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-14 19:41:23,026 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-14 19:41:23,026 INFO ^ 2024-04-14 19:41:23,026 INFO 19:38:49.564 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-14 19:41:23,026 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-14 19:41:23,026 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-14 19:41:23,026 INFO 2024-04-14 19:41:23,026 INFO ================================================================================ 2024-04-14 19:41:23,026 INFO 2024-04-14 19:38:58 5s elapsed 2024-04-14 19:41:23,026 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,026 INFO > Global (OK=416 KO=0 ) 2024-04-14 19:41:23,026 INFO > userRestLogin (OK=416 KO=0 ) 2024-04-14 19:41:23,026 INFO 2024-04-14 19:41:23,026 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,026 INFO [- ] 0% 2024-04-14 19:41:23,026 INFO waiting: 3616713 / active: 2 / done: 416 2024-04-14 19:41:23,026 INFO ================================================================================ 2024-04-14 19:41:23,026 INFO 2024-04-14 19:41:23,026 INFO 2024-04-14 19:41:23,026 INFO ================================================================================ 2024-04-14 19:41:23,026 INFO 2024-04-14 19:39:03 10s elapsed 2024-04-14 19:41:23,026 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,026 INFO > Global (OK=1666 KO=0 ) 2024-04-14 19:41:23,026 INFO > userRestLogin (OK=1666 KO=0 ) 2024-04-14 19:41:23,027 INFO 2024-04-14 19:41:23,027 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,027 INFO [- ] 0% 2024-04-14 19:41:23,027 INFO waiting: 3615462 / active: 3 / done: 1666 2024-04-14 19:41:23,027 INFO ================================================================================ 2024-04-14 19:41:23,027 INFO 2024-04-14 19:41:23,027 INFO 2024-04-14 19:41:23,027 INFO ================================================================================ 2024-04-14 19:41:23,027 INFO 2024-04-14 19:39:08 15s elapsed 2024-04-14 19:41:23,027 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,027 INFO > Global (OK=3750 KO=0 ) 2024-04-14 19:41:23,027 INFO > userRestLogin (OK=3750 KO=0 ) 2024-04-14 19:41:23,027 INFO 2024-04-14 19:41:23,027 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,027 INFO [- ] 0% 2024-04-14 19:41:23,027 INFO waiting: 3613376 / active: 5 / done: 3750 2024-04-14 19:41:23,027 INFO ================================================================================ 2024-04-14 19:41:23,027 INFO 2024-04-14 19:41:23,027 INFO 2024-04-14 19:41:23,027 INFO ================================================================================ 2024-04-14 19:41:23,027 INFO 2024-04-14 19:39:13 20s elapsed 2024-04-14 19:41:23,027 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,027 INFO > Global (OK=6666 KO=0 ) 2024-04-14 19:41:23,027 INFO > userRestLogin (OK=6666 KO=0 ) 2024-04-14 19:41:23,027 INFO 2024-04-14 19:41:23,027 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,027 INFO [- ] 0% 2024-04-14 19:41:23,027 INFO waiting: 3610458 / active: 7 / done: 6666 2024-04-14 19:41:23,027 INFO ================================================================================ 2024-04-14 19:41:23,027 INFO 2024-04-14 19:41:23,027 INFO 2024-04-14 19:41:23,027 INFO ================================================================================ 2024-04-14 19:41:23,027 INFO 2024-04-14 19:39:18 25s elapsed 2024-04-14 19:41:23,027 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,027 INFO > Global (OK=10415 KO=0 ) 2024-04-14 19:41:23,027 INFO > userRestLogin (OK=10415 KO=0 ) 2024-04-14 19:41:23,027 INFO 2024-04-14 19:41:23,027 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,027 INFO [- ] 0% 2024-04-14 19:41:23,027 INFO waiting: 3606708 / active: 8 / done: 10415 2024-04-14 19:41:23,027 INFO ================================================================================ 2024-04-14 19:41:23,028 INFO 2024-04-14 19:41:23,028 INFO 2024-04-14 19:41:23,028 INFO ================================================================================ 2024-04-14 19:41:23,028 INFO 2024-04-14 19:39:23 30s elapsed 2024-04-14 19:41:23,028 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,028 INFO > Global (OK=14979 KO=0 ) 2024-04-14 19:41:23,028 INFO > userRestLogin (OK=14979 KO=0 ) 2024-04-14 19:41:23,028 INFO 2024-04-14 19:41:23,028 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,028 INFO [- ] 0% 2024-04-14 19:41:23,028 INFO waiting: 3602128 / active: 24 / done: 14979 2024-04-14 19:41:23,028 INFO ================================================================================ 2024-04-14 19:41:23,028 INFO 2024-04-14 19:41:23,028 INFO 2024-04-14 19:41:23,028 INFO ================================================================================ 2024-04-14 19:41:23,028 INFO 2024-04-14 19:39:28 35s elapsed 2024-04-14 19:41:23,028 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,028 INFO > Global (OK=19965 KO=0 ) 2024-04-14 19:41:23,028 INFO > userRestLogin (OK=19965 KO=0 ) 2024-04-14 19:41:23,028 INFO 2024-04-14 19:41:23,028 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,028 INFO [- ] 0% 2024-04-14 19:41:23,028 INFO waiting: 3597151 / active: 16 / done: 19964 2024-04-14 19:41:23,028 INFO ================================================================================ 2024-04-14 19:41:23,028 INFO 2024-04-14 19:41:23,028 INFO 2024-04-14 19:41:23,028 INFO ================================================================================ 2024-04-14 19:41:23,028 INFO 2024-04-14 19:39:33 40s elapsed 2024-04-14 19:41:23,028 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,028 INFO > Global (OK=25030 KO=0 ) 2024-04-14 19:41:23,028 INFO [...] 2024-04-14 19:41:23,031 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,031 INFO > Global (OK=3592425 KO=0 ) 2024-04-14 19:41:23,031 INFO > userRestLogin (OK=3592425 KO=0 ) 2024-04-14 19:41:23,031 INFO 2024-04-14 19:41:23,031 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,031 INFO [#########################################################################-] 99% 2024-04-14 19:41:23,031 INFO waiting: 24695 / active: 11 / done: 3592425 2024-04-14 19:41:23,031 INFO ================================================================================ 2024-04-14 19:41:23,031 INFO 2024-04-14 19:41:23,031 INFO 2024-04-14 19:41:23,031 INFO ================================================================================ 2024-04-14 19:41:23,031 INFO 2024-04-14 20:39:03 3610s elapsed 2024-04-14 19:41:23,031 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,031 INFO > Global (OK=3597348 KO=0 ) 2024-04-14 19:41:23,031 INFO > userRestLogin (OK=3597348 KO=0 ) 2024-04-14 19:41:23,031 INFO 2024-04-14 19:41:23,031 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,031 INFO [#########################################################################-] 99% 2024-04-14 19:41:23,031 INFO waiting: 19775 / active: 9 / done: 3597347 2024-04-14 19:41:23,031 INFO ================================================================================ 2024-04-14 19:41:23,031 INFO 2024-04-14 19:41:23,031 INFO 2024-04-14 19:41:23,031 INFO ================================================================================ 2024-04-14 19:41:23,031 INFO 2024-04-14 20:39:08 3615s elapsed 2024-04-14 19:41:23,031 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,031 INFO > Global (OK=3602381 KO=0 ) 2024-04-14 19:41:23,031 INFO > userRestLogin (OK=3602381 KO=0 ) 2024-04-14 19:41:23,031 INFO 2024-04-14 19:41:23,031 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,031 INFO [#########################################################################-] 99% 2024-04-14 19:41:23,031 INFO waiting: 14739 / active: 11 / done: 3602381 2024-04-14 19:41:23,031 INFO ================================================================================ 2024-04-14 19:41:23,031 INFO 2024-04-14 19:41:23,031 INFO 2024-04-14 19:41:23,032 INFO ================================================================================ 2024-04-14 19:41:23,032 INFO 2024-04-14 20:39:13 3620s elapsed 2024-04-14 19:41:23,032 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,032 INFO > Global (OK=3607299 KO=0 ) 2024-04-14 19:41:23,032 INFO > userRestLogin (OK=3607299 KO=0 ) 2024-04-14 19:41:23,032 INFO 2024-04-14 19:41:23,032 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,032 INFO [#########################################################################-] 99% 2024-04-14 19:41:23,032 INFO waiting: 9822 / active: 10 / done: 3607299 2024-04-14 19:41:23,032 INFO ================================================================================ 2024-04-14 19:41:23,032 INFO 2024-04-14 19:41:23,032 INFO 2024-04-14 19:41:23,032 INFO ================================================================================ 2024-04-14 19:41:23,032 INFO 2024-04-14 20:39:18 3625s elapsed 2024-04-14 19:41:23,032 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,032 INFO > Global (OK=3612162 KO=0 ) 2024-04-14 19:41:23,032 INFO > userRestLogin (OK=3612162 KO=0 ) 2024-04-14 19:41:23,032 INFO 2024-04-14 19:41:23,032 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,032 INFO [#########################################################################-] 99% 2024-04-14 19:41:23,032 INFO waiting: 4961 / active: 8 / done: 3612162 2024-04-14 19:41:23,032 INFO ================================================================================ 2024-04-14 19:41:23,032 INFO 2024-04-14 19:41:23,032 INFO 2024-04-14 19:41:23,032 INFO ================================================================================ 2024-04-14 19:41:23,032 INFO 2024-04-14 20:39:23 3630s elapsed 2024-04-14 19:41:23,032 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,032 INFO > Global (OK=3617130 KO=0 ) 2024-04-14 19:41:23,032 INFO > userRestLogin (OK=3617130 KO=0 ) 2024-04-14 19:41:23,032 INFO 2024-04-14 19:41:23,032 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,032 INFO [#########################################################################-] 99% 2024-04-14 19:41:23,032 INFO waiting: 0 / active: 1 / done: 3617130 2024-04-14 19:41:23,032 INFO ================================================================================ 2024-04-14 19:41:23,032 INFO 2024-04-14 19:41:23,032 INFO 2024-04-14 19:41:23,032 INFO ================================================================================ 2024-04-14 19:41:23,032 INFO 2024-04-14 20:39:23 3630s elapsed 2024-04-14 19:41:23,032 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 19:41:23,032 INFO > Global (OK=3617131 KO=0 ) 2024-04-14 19:41:23,032 INFO > userRestLogin (OK=3617131 KO=0 ) 2024-04-14 19:41:23,032 INFO 2024-04-14 19:41:23,033 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 19:41:23,033 INFO [##########################################################################]100% 2024-04-14 19:41:23,033 INFO waiting: 0 / active: 0 / done: 3617131 2024-04-14 19:41:23,033 INFO ================================================================================ 2024-04-14 19:41:23,033 INFO 2024-04-14 19:41:23,033 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-14 19:41:23,033 INFO 2024-04-14 19:41:23,033 INFO ---------------------- 2024-04-14 19:41:23,033 INFO [14/Apr/2024 20:39:23] Create tar file authn-users-1000.tar.gz 2024-04-14 19:41:23,033 INFO ---------------------- 2024-04-14 19:41:23,033 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-14 19:41:23,033 INFO RESULT_FOLDER=authn-users-1000 2024-04-14 19:41:23,033 INFO SIMULATION_PATH=authn-users-1000/loadtest-20240414183853102 2024-04-14 19:41:23,033 INFO PWD=/results/gatling/authn-users-1000/loadtest-20240414183853102 2024-04-14 19:41:23,033 INFO [cmd] tar -czvf /results/gatling//authn-users-1000.tar.gz --exclude=simulation.log * 2024-04-14 19:41:23,033 INFO /results/gatling 2024-04-14 19:41:23,033 INFO 2024-04-14 19:41:23,033 INFO ---------------------- 2024-04-14 19:41:23,033 INFO [14/Apr/2024 20:39:23] Update /results/gatling//latest 2024-04-14 19:41:23,033 INFO ---------------------- 2024-04-14 19:41:23,033 INFO 2024-04-14 19:41:23,033 INFO ---------------------- 2024-04-14 19:41:23,033 INFO [14/Apr/2024 20:39:23] Update /results/gatling//latest.tar.gz 2024-04-14 19:41:23,033 INFO ---------------------- 2024-04-14 19:41:23,033 INFO ________________________________________________________________________________ [2024-04-14 19:41:23] authn-users-1000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-14 19:41:23,033 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/02_authn-users-1000/stderr.txt is small (0 lines), printing all lines: 2024-04-14 19:41:23,033 DEBUG ----- output ----- 2024-04-14 19:41:23,034 DEBUG 2024-04-14 19:41:23,034 INFO ________________________________________________________________________________ [2024-04-14 19:41:23] authn-users-1000 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-14 19:41:23] authn-users-1000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-14 19:41:23] authn-users-1000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped