--Task-- name: authn-users-100 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: [] preceding_task: None options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 100, 'duration': 3600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-14 17:35:41] authn-users-100 pre : N/A ________________________________________________________________________________ [2024-04-14 17:35:41] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-04-14 17:35:41] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-100/stdout.txt Pod log : /results/orders/authn-users-100 2024-04-14 17:35:41,898 INFO 2024-04-14 17:35:41,899 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 17:35:41,899 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 17:35:42,232 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 17:35:42,233 INFO [loop_until]: OK (rc = 0) 2024-04-14 17:35:42,233 DEBUG --- stdout --- 2024-04-14 17:35:42,233 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 17:35:42,233 DEBUG --- stderr --- 2024-04-14 17:35:42,233 DEBUG [2024-04-14 17:35:42] - INFO: Get overseer pod resources requests memory 2024-04-14 17:35:42,233 INFO 2024-04-14 17:35:42,233 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-5584f6c4db-bcn5q --output jsonpath={.spec.containers[].resources.requests.memory} 2024-04-14 17:35:42,233 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 17:35:42,551 INFO [loop_until]: OK (rc = 0) 2024-04-14 17:35:42,552 DEBUG --- stdout --- 2024-04-14 17:35:42,552 DEBUG 4Gi 2024-04-14 17:35:42,552 DEBUG --- stderr --- 2024-04-14 17:35:42,552 DEBUG ________________________________________________________________________________ [2024-04-14 17:35:42] authn-users-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-14 17:35:42,553 INFO 2024-04-14 17:35:42,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 17:35:42,553 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 17:35:42,910 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 17:35:42,910 INFO [loop_until]: OK (rc = 0) 2024-04-14 17:35:42,910 DEBUG --- stdout --- 2024-04-14 17:35:42,910 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 17:35:42,910 DEBUG --- stderr --- 2024-04-14 17:35:42,910 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-100 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-14 17:35:42] authn-users-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-14 17:35:42,911 INFO 2024-04-14 17:35:42,911 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 17:35:42,911 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 17:35:43,258 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 17:35:43,259 INFO [loop_until]: OK (rc = 0) 2024-04-14 17:35:43,259 DEBUG --- stdout --- 2024-04-14 17:35:43,259 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 17:35:43,259 DEBUG --- stderr --- 2024-04-14 17:35:43,259 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/order.json { "name": "authn-users-100", "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-100 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-100", "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=100 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-14 17:35:43] authn-users-100 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 17:35:43,260 INFO 2024-04-14 17:35:43,260 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-14 17:35:43,261 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 17:35:43,497 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 17:35:43,497 INFO [loop_until]: OK (rc = 0) 2024-04-14 17:35:43,497 DEBUG --- stdout --- 2024-04-14 17:35:43,497 DEBUG WAITING 2024-04-14 17:35:43,497 DEBUG --- stderr --- 2024-04-14 17:35:43,498 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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-14 17:35:43] authn-users-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-14 17:35:43,498 INFO 2024-04-14 17:35:43,498 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 17:35:43,498 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 17:35:43,809 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 17:35:43,809 INFO [loop_until]: OK (rc = 0) 2024-04-14 17:35:43,809 DEBUG --- stdout --- 2024-04-14 17:35:43,809 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 17:35:43,809 DEBUG --- stderr --- 2024-04-14 17:35:43,809 DEBUG 2024-04-14 17:35:43,810 INFO 2024-04-14 17:35:43,810 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/01_authn-users-100/order.json overseer-0-5584f6c4db-bcn5q:/results/orders/order.json 2024-04-14 17:35:43,810 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 17:35:45,236 INFO [loop_until]: OK (rc = 0) 2024-04-14 17:35:45,236 DEBUG --- stdout --- 2024-04-14 17:35:45,236 DEBUG 2024-04-14 17:35:45,236 DEBUG --- stderr --- 2024-04-14 17:35:45,236 DEBUG Order has been successfully forwarded to overseer [2024-04-14 17:35:45] - INFO: Check if Gatling simulation started successfully 2024-04-14 17:35:45,237 INFO 2024-04-14 17:35:45,237 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 17:35:45,237 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 17:35:45,539 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 17:35:45,539 INFO [loop_until]: OK (rc = 0) 2024-04-14 17:35:45,539 DEBUG --- stdout --- 2024-04-14 17:35:45,539 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 17:35:45,539 DEBUG --- stderr --- 2024-04-14 17:35:45,539 DEBUG 2024-04-14 17:35:45,540 INFO 2024-04-14 17:35:45,540 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-100/stdout.txt | grep "started..." 2024-04-14 17:35:45,540 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-14 17:35:46,292 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-14 17:35:52,065 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-14 17:35:57,826 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-14 17:36:03,582 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-14 17:36:03,582 INFO [loop_until]: OK (rc = 0) 2024-04-14 17:36:03,582 DEBUG --- stdout --- 2024-04-14 17:36:03,582 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 18:35:48.350 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 18:35:56.243 [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"); ^ 18:35:56.247 [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"); ^ 18:35:58.796 [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 17:36:03,582 DEBUG --- stderr --- 2024-04-14 17:36:03,582 DEBUG ________________________________________________________________________________ [2024-04-14 17:36:03] authn-users-100 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 10s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-04-14 18:38:14] authn-users-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-14 18:38:14,246 INFO 2024-04-14 18:38:14,246 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:14,246 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:14,597 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:14,598 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:14,598 DEBUG --- stdout --- 2024-04-14 18:38:14,598 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:14,598 DEBUG --- stderr --- 2024-04-14 18:38:14,598 DEBUG 2024-04-14 18:38:14,598 INFO 2024-04-14 18:38:14,598 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 18:38:14,598 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 18:38:15,402 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:15,403 DEBUG --- stdout --- 2024-04-14 18:38:15,403 DEBUG 2024-04-14 18:38:15,403 DEBUG --- stderr --- 2024-04-14 18:38:15,403 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-04-14 18:38:15] authn-users-100 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-14 18:38:15] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/order.json 2024-04-14 18:38:15,403 INFO 2024-04-14 18:38:15,403 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:15,403 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:15,711 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:15,711 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:15,711 DEBUG --- stdout --- 2024-04-14 18:38:15,711 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:15,711 DEBUG --- stderr --- 2024-04-14 18:38:15,711 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 18:38:15,711 INFO 2024-04-14 18:38:15,711 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:15,712 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:16,045 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:16,045 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:16,045 DEBUG --- stdout --- 2024-04-14 18:38:16,045 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:16,045 DEBUG --- stderr --- 2024-04-14 18:38:16,045 DEBUG 2024-04-14 18:38:16,046 INFO 2024-04-14 18:38:16,046 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-100/rc.txt" ]; then echo "/results/orders/authn-users-100/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-100/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/rc.txt file found" 2024-04-14 18:38:16,046 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 18:38:16,880 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 18:38:16,881 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:16,881 DEBUG --- stdout --- 2024-04-14 18:38:16,881 DEBUG /results/orders/authn-users-100/rc.txt file found 2024-04-14 18:38:16,881 DEBUG --- stderr --- 2024-04-14 18:38:16,881 DEBUG 2024-04-14 18:38:16,882 INFO 2024-04-14 18:38:16,882 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:16,882 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:17,215 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:17,215 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:17,215 DEBUG --- stdout --- 2024-04-14 18:38:17,215 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:17,215 DEBUG --- stderr --- 2024-04-14 18:38:17,215 DEBUG 2024-04-14 18:38:17,216 INFO 2024-04-14 18:38:17,216 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-100/rc.txt 2024-04-14 18:38:18,061 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 18:38:18,061 DEBUG --- stdout --- 2024-04-14 18:38:18,062 DEBUG 4 /results/orders/authn-users-100/rc.txt 2024-04-14 18:38:18,062 DEBUG --- stderr --- 2024-04-14 18:38:18,062 DEBUG 2024-04-14 18:38:18,062 INFO - Download file (size 4k) 2024-04-14 18:38:18,062 INFO 2024-04-14 18:38:18,062 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:18,062 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:18,383 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:18,383 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:18,384 DEBUG --- stdout --- 2024-04-14 18:38:18,384 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:18,384 DEBUG --- stderr --- 2024-04-14 18:38:18,384 DEBUG source : /results/orders/authn-users-100/rc.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-14 18:38:18,384 INFO 2024-04-14 18:38:18,384 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:18,384 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:18,702 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:18,702 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:18,702 DEBUG --- stdout --- 2024-04-14 18:38:18,702 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:18,702 DEBUG --- stderr --- 2024-04-14 18:38:18,702 DEBUG 2024-04-14 18:38:18,703 INFO 2024-04-14 18:38:18,703 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-100/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt 2024-04-14 18:38:18,703 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 18:38:19,485 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:19,485 DEBUG --- stdout --- 2024-04-14 18:38:19,485 DEBUG tar: Removing leading `/' from member names 2024-04-14 18:38:19,485 DEBUG --- stderr --- 2024-04-14 18:38:19,485 DEBUG 2024-04-14 18:38:19,486 INFO 2024-04-14 18:38:19,486 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:19,486 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:19,795 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:19,795 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:19,795 DEBUG --- stdout --- 2024-04-14 18:38:19,795 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:19,795 DEBUG --- stderr --- 2024-04-14 18:38:19,795 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 18:38:19,795 INFO 2024-04-14 18:38:19,795 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:19,795 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:20,111 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:20,112 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:20,112 DEBUG --- stdout --- 2024-04-14 18:38:20,112 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:20,112 DEBUG --- stderr --- 2024-04-14 18:38:20,112 DEBUG 2024-04-14 18:38:20,112 INFO 2024-04-14 18:38:20,112 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-100/stderr.txt" ]; then echo "/results/orders/authn-users-100/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-100/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/stderr.txt file found" 2024-04-14 18:38:20,112 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 18:38:20,942 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 18:38:20,942 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:20,942 DEBUG --- stdout --- 2024-04-14 18:38:20,942 DEBUG /results/orders/authn-users-100/stderr.txt file found 2024-04-14 18:38:20,942 DEBUG --- stderr --- 2024-04-14 18:38:20,942 DEBUG 2024-04-14 18:38:20,942 INFO 2024-04-14 18:38:20,942 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:20,942 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:21,262 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:21,262 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:21,262 DEBUG --- stdout --- 2024-04-14 18:38:21,262 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:21,263 DEBUG --- stderr --- 2024-04-14 18:38:21,263 DEBUG 2024-04-14 18:38:21,263 INFO 2024-04-14 18:38:21,263 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-100/stderr.txt 2024-04-14 18:38:22,089 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 18:38:22,091 DEBUG --- stdout --- 2024-04-14 18:38:22,091 DEBUG 0 /results/orders/authn-users-100/stderr.txt 2024-04-14 18:38:22,091 DEBUG --- stderr --- 2024-04-14 18:38:22,091 DEBUG 2024-04-14 18:38:22,091 INFO - Download file (size 0k) 2024-04-14 18:38:22,092 INFO 2024-04-14 18:38:22,092 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:22,092 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:22,454 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:22,454 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:22,454 DEBUG --- stdout --- 2024-04-14 18:38:22,454 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:22,455 DEBUG --- stderr --- 2024-04-14 18:38:22,455 DEBUG source : /results/orders/authn-users-100/stderr.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-14 18:38:22,455 INFO 2024-04-14 18:38:22,455 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:22,455 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:22,804 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:22,804 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:22,804 DEBUG --- stdout --- 2024-04-14 18:38:22,804 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:22,804 DEBUG --- stderr --- 2024-04-14 18:38:22,805 DEBUG 2024-04-14 18:38:22,805 INFO 2024-04-14 18:38:22,805 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-100/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt 2024-04-14 18:38:22,805 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 18:38:23,551 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:23,551 DEBUG --- stdout --- 2024-04-14 18:38:23,552 DEBUG tar: Removing leading `/' from member names 2024-04-14 18:38:23,552 DEBUG --- stderr --- 2024-04-14 18:38:23,552 DEBUG 2024-04-14 18:38:23,552 INFO 2024-04-14 18:38:23,552 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:23,552 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:23,856 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:23,857 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:23,857 DEBUG --- stdout --- 2024-04-14 18:38:23,857 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:23,857 DEBUG --- stderr --- 2024-04-14 18:38:23,857 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 18:38:23,857 INFO 2024-04-14 18:38:23,857 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:23,857 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:24,163 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:24,163 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:24,164 DEBUG --- stdout --- 2024-04-14 18:38:24,164 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:24,164 DEBUG --- stderr --- 2024-04-14 18:38:24,164 DEBUG 2024-04-14 18:38:24,164 INFO 2024-04-14 18:38:24,164 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-100/stdout.txt" ]; then echo "/results/orders/authn-users-100/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-100/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/stdout.txt file found" 2024-04-14 18:38:24,164 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 18:38:24,994 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 18:38:24,994 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:24,994 DEBUG --- stdout --- 2024-04-14 18:38:24,995 DEBUG /results/orders/authn-users-100/stdout.txt file found 2024-04-14 18:38:24,995 DEBUG --- stderr --- 2024-04-14 18:38:24,995 DEBUG 2024-04-14 18:38:24,995 INFO 2024-04-14 18:38:24,995 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:24,995 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:25,322 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:25,322 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:25,322 DEBUG --- stdout --- 2024-04-14 18:38:25,322 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:25,322 DEBUG --- stderr --- 2024-04-14 18:38:25,322 DEBUG 2024-04-14 18:38:25,323 INFO 2024-04-14 18:38:25,323 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-100/stdout.txt 2024-04-14 18:38:26,161 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 18:38:26,161 DEBUG --- stdout --- 2024-04-14 18:38:26,161 DEBUG 504 /results/orders/authn-users-100/stdout.txt 2024-04-14 18:38:26,161 DEBUG --- stderr --- 2024-04-14 18:38:26,161 DEBUG 2024-04-14 18:38:26,162 INFO - Download file (size 504k) 2024-04-14 18:38:26,162 INFO 2024-04-14 18:38:26,162 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:26,162 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:26,511 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:26,512 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:26,512 DEBUG --- stdout --- 2024-04-14 18:38:26,512 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:26,512 DEBUG --- stderr --- 2024-04-14 18:38:26,512 DEBUG source : /results/orders/authn-users-100/stdout.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-14 18:38:26,512 INFO 2024-04-14 18:38:26,512 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:26,512 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:26,869 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:26,869 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:26,869 DEBUG --- stdout --- 2024-04-14 18:38:26,869 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:26,869 DEBUG --- stderr --- 2024-04-14 18:38:26,869 DEBUG 2024-04-14 18:38:26,870 INFO 2024-04-14 18:38:26,870 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-100/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stdout.txt 2024-04-14 18:38:26,870 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 18:38:27,961 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:27,961 DEBUG --- stdout --- 2024-04-14 18:38:27,961 DEBUG tar: Removing leading `/' from member names 2024-04-14 18:38:27,961 DEBUG --- stderr --- 2024-04-14 18:38:27,961 DEBUG ________________________________________________________________________________ [2024-04-14 18:38:27] authn-users-100 show_cmd : Display order command ________________________________________________________________________________ 2024-04-14 18:38:27,962 INFO 2024-04-14 18:38:27,962 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:27,962 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:28,301 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:28,301 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:28,301 DEBUG --- stdout --- 2024-04-14 18:38:28,301 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:28,301 DEBUG --- stderr --- 2024-04-14 18:38:28,301 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-14 18:38:28,301 INFO 2024-04-14 18:38:28,301 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:28,301 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:28,639 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:28,639 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:28,639 DEBUG --- stdout --- 2024-04-14 18:38:28,639 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:28,639 DEBUG --- stderr --- 2024-04-14 18:38:28,639 DEBUG 2024-04-14 18:38:28,640 INFO 2024-04-14 18:38:28,640 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-100/cmd.txt" ]; then echo "/results/orders/authn-users-100/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-100/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-100/cmd.txt file found" 2024-04-14 18:38:28,640 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-14 18:38:29,477 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-14 18:38:29,477 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:29,477 DEBUG --- stdout --- 2024-04-14 18:38:29,477 DEBUG /results/orders/authn-users-100/cmd.txt file found 2024-04-14 18:38:29,477 DEBUG --- stderr --- 2024-04-14 18:38:29,477 DEBUG 2024-04-14 18:38:29,477 INFO 2024-04-14 18:38:29,477 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:29,478 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:29,802 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:29,802 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:29,802 DEBUG --- stdout --- 2024-04-14 18:38:29,802 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:29,802 DEBUG --- stderr --- 2024-04-14 18:38:29,802 DEBUG 2024-04-14 18:38:29,803 INFO 2024-04-14 18:38:29,803 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-100/cmd.txt 2024-04-14 18:38:30,646 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-14 18:38:30,646 DEBUG --- stdout --- 2024-04-14 18:38:30,646 DEBUG 4 /results/orders/authn-users-100/cmd.txt 2024-04-14 18:38:30,646 DEBUG --- stderr --- 2024-04-14 18:38:30,646 DEBUG 2024-04-14 18:38:30,646 INFO - Download file (size 4k) 2024-04-14 18:38:30,647 INFO 2024-04-14 18:38:30,647 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:30,647 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:30,950 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:30,951 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:30,951 DEBUG --- stdout --- 2024-04-14 18:38:30,951 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:30,951 DEBUG --- stderr --- 2024-04-14 18:38:30,951 DEBUG source : /results/orders/authn-users-100/cmd.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-14 18:38:30,951 INFO 2024-04-14 18:38:30,951 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:30,951 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-14 18:38:31,251 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-14 18:38:31,251 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:31,251 DEBUG --- stdout --- 2024-04-14 18:38:31,251 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-14 18:38:31,252 DEBUG --- stderr --- 2024-04-14 18:38:31,252 DEBUG 2024-04-14 18:38:31,252 INFO 2024-04-14 18:38:31,252 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-100/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt 2024-04-14 18:38:31,252 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-14 18:38:32,007 INFO [loop_until]: OK (rc = 0) 2024-04-14 18:38:32,007 DEBUG --- stdout --- 2024-04-14 18:38:32,007 DEBUG tar: Removing leading `/' from member names 2024-04-14 18:38:32,007 DEBUG --- stderr --- 2024-04-14 18:38:32,007 DEBUG 2024-04-14 18:38:32,007 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/cmd.txt is small (1 lines), printing all lines: 2024-04-14 18:38:32,007 DEBUG ----- output ----- 2024-04-14 18:38:32,008 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-100 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-14 18:38:32,008 INFO ________________________________________________________________________________ [2024-04-14 18:38:32] authn-users-100 show_rc : Display rc order ________________________________________________________________________________ 2024-04-14 18:38:32,008 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/rc.txt is small (1 lines), printing all lines: 2024-04-14 18:38:32,008 DEBUG ----- output ----- 2024-04-14 18:38:32,008 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-14 18:38:32,008 INFO ________________________________________________________________________________ [2024-04-14 18:38:32] authn-users-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-14 18:38:32,009 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/01_authn-users-100/stdout.txt is 8742 lines): 2024-04-14 18:38:32,009 DEBUG ----- output ----- 2024-04-14 18:38:32,010 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-14 18:38:32,010 INFO 18:35:48.350 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-14 18:38:32,010 INFO 18:35:56.243 [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 18:38:32,010 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-14 18:38:32,010 INFO ^ 2024-04-14 18:38:32,010 INFO 18:35:56.247 [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 18:38:32,010 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-14 18:38:32,010 INFO ^ 2024-04-14 18:38:32,010 INFO 18:35:58.796 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-14 18:38:32,010 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-14 18:38:32,010 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-14 18:38:32,010 INFO 2024-04-14 18:38:32,010 INFO ================================================================================ 2024-04-14 18:38:32,010 INFO 2024-04-14 18:36:07 5s elapsed 2024-04-14 18:38:32,010 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,010 INFO > Global (OK=41 KO=0 ) 2024-04-14 18:38:32,010 INFO > userRestLogin (OK=41 KO=0 ) 2024-04-14 18:38:32,010 INFO 2024-04-14 18:38:32,010 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,010 INFO [- ] 0% 2024-04-14 18:38:32,010 INFO waiting: 361560 / active: 1 / done: 41 2024-04-14 18:38:32,010 INFO ================================================================================ 2024-04-14 18:38:32,010 INFO 2024-04-14 18:38:32,010 INFO 2024-04-14 18:38:32,010 INFO ================================================================================ 2024-04-14 18:38:32,010 INFO 2024-04-14 18:36:12 10s elapsed 2024-04-14 18:38:32,010 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,010 INFO > Global (OK=166 KO=0 ) 2024-04-14 18:38:32,010 INFO > userRestLogin (OK=166 KO=0 ) 2024-04-14 18:38:32,010 INFO 2024-04-14 18:38:32,010 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,010 INFO [- ] 0% 2024-04-14 18:38:32,010 INFO waiting: 361435 / active: 1 / done: 166 2024-04-14 18:38:32,010 INFO ================================================================================ 2024-04-14 18:38:32,010 INFO 2024-04-14 18:38:32,010 INFO 2024-04-14 18:38:32,010 INFO ================================================================================ 2024-04-14 18:38:32,010 INFO 2024-04-14 18:36:17 15s elapsed 2024-04-14 18:38:32,011 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,011 INFO > Global (OK=375 KO=0 ) 2024-04-14 18:38:32,011 INFO > userRestLogin (OK=375 KO=0 ) 2024-04-14 18:38:32,011 INFO 2024-04-14 18:38:32,011 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,011 INFO [- ] 0% 2024-04-14 18:38:32,011 INFO waiting: 361226 / active: 1 / done: 375 2024-04-14 18:38:32,011 INFO ================================================================================ 2024-04-14 18:38:32,011 INFO 2024-04-14 18:38:32,011 INFO 2024-04-14 18:38:32,011 INFO ================================================================================ 2024-04-14 18:38:32,011 INFO 2024-04-14 18:36:22 20s elapsed 2024-04-14 18:38:32,011 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,011 INFO > Global (OK=666 KO=0 ) 2024-04-14 18:38:32,011 INFO > userRestLogin (OK=666 KO=0 ) 2024-04-14 18:38:32,011 INFO 2024-04-14 18:38:32,011 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,011 INFO [- ] 0% 2024-04-14 18:38:32,011 INFO waiting: 360935 / active: 1 / done: 666 2024-04-14 18:38:32,011 INFO ================================================================================ 2024-04-14 18:38:32,011 INFO 2024-04-14 18:38:32,011 INFO 2024-04-14 18:38:32,011 INFO ================================================================================ 2024-04-14 18:38:32,011 INFO 2024-04-14 18:36:27 25s elapsed 2024-04-14 18:38:32,011 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,011 INFO > Global (OK=1041 KO=0 ) 2024-04-14 18:38:32,011 INFO > userRestLogin (OK=1041 KO=0 ) 2024-04-14 18:38:32,011 INFO 2024-04-14 18:38:32,011 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,011 INFO [- ] 0% 2024-04-14 18:38:32,011 INFO waiting: 360560 / active: 1 / done: 1041 2024-04-14 18:38:32,011 INFO ================================================================================ 2024-04-14 18:38:32,011 INFO 2024-04-14 18:38:32,011 INFO 2024-04-14 18:38:32,011 INFO ================================================================================ 2024-04-14 18:38:32,011 INFO 2024-04-14 18:36:32 30s elapsed 2024-04-14 18:38:32,011 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,011 INFO > Global (OK=1499 KO=0 ) 2024-04-14 18:38:32,011 INFO > userRestLogin (OK=1499 KO=0 ) 2024-04-14 18:38:32,011 INFO 2024-04-14 18:38:32,011 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,011 INFO [- ] 0% 2024-04-14 18:38:32,012 INFO waiting: 360102 / active: 1 / done: 1499 2024-04-14 18:38:32,012 INFO ================================================================================ 2024-04-14 18:38:32,012 INFO 2024-04-14 18:38:32,012 INFO 2024-04-14 18:38:32,012 INFO ================================================================================ 2024-04-14 18:38:32,012 INFO 2024-04-14 18:36:37 35s elapsed 2024-04-14 18:38:32,012 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,012 INFO > Global (OK=2016 KO=0 ) 2024-04-14 18:38:32,012 INFO > userRestLogin (OK=2016 KO=0 ) 2024-04-14 18:38:32,012 INFO 2024-04-14 18:38:32,012 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,012 INFO [- ] 0% 2024-04-14 18:38:32,012 INFO waiting: 359585 / active: 1 / done: 2016 2024-04-14 18:38:32,012 INFO ================================================================================ 2024-04-14 18:38:32,012 INFO 2024-04-14 18:38:32,012 INFO 2024-04-14 18:38:32,012 INFO ================================================================================ 2024-04-14 18:38:32,012 INFO 2024-04-14 18:36:42 40s elapsed 2024-04-14 18:38:32,012 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,012 INFO > Global (OK=2505 KO=0 ) 2024-04-14 18:38:32,012 INFO [...] 2024-04-14 18:38:32,014 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,014 INFO > Global (OK=358632 KO=0 ) 2024-04-14 18:38:32,014 INFO > userRestLogin (OK=358632 KO=0 ) 2024-04-14 18:38:32,014 INFO 2024-04-14 18:38:32,014 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,015 INFO [#########################################################################-] 99% 2024-04-14 18:38:32,015 INFO waiting: 2969 / active: 1 / done: 358632 2024-04-14 18:38:32,015 INFO ================================================================================ 2024-04-14 18:38:32,015 INFO 2024-04-14 18:38:32,015 INFO 2024-04-14 18:38:32,015 INFO ================================================================================ 2024-04-14 18:38:32,015 INFO 2024-04-14 19:36:07 3605s elapsed 2024-04-14 18:38:32,015 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,015 INFO > Global (OK=359133 KO=0 ) 2024-04-14 18:38:32,015 INFO > userRestLogin (OK=359133 KO=0 ) 2024-04-14 18:38:32,015 INFO 2024-04-14 18:38:32,015 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,015 INFO [#########################################################################-] 99% 2024-04-14 18:38:32,015 INFO waiting: 2468 / active: 1 / done: 359133 2024-04-14 18:38:32,015 INFO ================================================================================ 2024-04-14 18:38:32,015 INFO 2024-04-14 18:38:32,015 INFO 2024-04-14 18:38:32,015 INFO ================================================================================ 2024-04-14 18:38:32,015 INFO 2024-04-14 19:36:12 3610s elapsed 2024-04-14 18:38:32,015 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,015 INFO > Global (OK=359624 KO=0 ) 2024-04-14 18:38:32,015 INFO > userRestLogin (OK=359624 KO=0 ) 2024-04-14 18:38:32,015 INFO 2024-04-14 18:38:32,015 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,015 INFO [######################################################################### ] 99% 2024-04-14 18:38:32,015 INFO waiting: 1978 / active: 0 / done: 359624 2024-04-14 18:38:32,015 INFO ================================================================================ 2024-04-14 18:38:32,015 INFO 2024-04-14 18:38:32,015 INFO 2024-04-14 18:38:32,015 INFO ================================================================================ 2024-04-14 18:38:32,015 INFO 2024-04-14 19:36:17 3615s elapsed 2024-04-14 18:38:32,015 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,015 INFO > Global (OK=360108 KO=0 ) 2024-04-14 18:38:32,015 INFO > userRestLogin (OK=360108 KO=0 ) 2024-04-14 18:38:32,015 INFO 2024-04-14 18:38:32,015 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,015 INFO [#########################################################################-] 99% 2024-04-14 18:38:32,015 INFO waiting: 1493 / active: 1 / done: 360108 2024-04-14 18:38:32,015 INFO ================================================================================ 2024-04-14 18:38:32,015 INFO 2024-04-14 18:38:32,015 INFO 2024-04-14 18:38:32,015 INFO ================================================================================ 2024-04-14 18:38:32,016 INFO 2024-04-14 19:36:22 3620s elapsed 2024-04-14 18:38:32,016 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,016 INFO > Global (OK=360606 KO=0 ) 2024-04-14 18:38:32,016 INFO > userRestLogin (OK=360606 KO=0 ) 2024-04-14 18:38:32,016 INFO 2024-04-14 18:38:32,016 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,016 INFO [#########################################################################-] 99% 2024-04-14 18:38:32,016 INFO waiting: 995 / active: 1 / done: 360606 2024-04-14 18:38:32,016 INFO ================================================================================ 2024-04-14 18:38:32,016 INFO 2024-04-14 18:38:32,016 INFO 2024-04-14 18:38:32,016 INFO ================================================================================ 2024-04-14 18:38:32,016 INFO 2024-04-14 19:36:27 3625s elapsed 2024-04-14 18:38:32,016 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,016 INFO > Global (OK=361072 KO=0 ) 2024-04-14 18:38:32,016 INFO > userRestLogin (OK=361072 KO=0 ) 2024-04-14 18:38:32,016 INFO 2024-04-14 18:38:32,016 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,016 INFO [#########################################################################-] 99% 2024-04-14 18:38:32,016 INFO waiting: 527 / active: 3 / done: 361072 2024-04-14 18:38:32,016 INFO ================================================================================ 2024-04-14 18:38:32,016 INFO 2024-04-14 18:38:32,016 INFO 2024-04-14 18:38:32,016 INFO ================================================================================ 2024-04-14 18:38:32,016 INFO 2024-04-14 19:36:32 3630s elapsed 2024-04-14 18:38:32,016 INFO ---- Requests ------------------------------------------------------------------ 2024-04-14 18:38:32,016 INFO > Global (OK=361602 KO=0 ) 2024-04-14 18:38:32,016 INFO > userRestLogin (OK=361602 KO=0 ) 2024-04-14 18:38:32,016 INFO 2024-04-14 18:38:32,016 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-14 18:38:32,016 INFO [##########################################################################]100% 2024-04-14 18:38:32,016 INFO waiting: 0 / active: 0 / done: 361602 2024-04-14 18:38:32,016 INFO ================================================================================ 2024-04-14 18:38:32,016 INFO 2024-04-14 18:38:32,016 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-04-14 18:38:32,016 INFO 2024-04-14 18:38:32,016 INFO ---------------------- 2024-04-14 18:38:32,016 INFO [14/Apr/2024 19:36:32] Create tar file authn-users-100.tar.gz 2024-04-14 18:38:32,016 INFO ---------------------- 2024-04-14 18:38:32,016 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-14 18:38:32,016 INFO RESULT_FOLDER=authn-users-100 2024-04-14 18:38:32,016 INFO SIMULATION_PATH=authn-users-100/loadtest-20240414173601832 2024-04-14 18:38:32,017 INFO PWD=/results/gatling/authn-users-100/loadtest-20240414173601832 2024-04-14 18:38:32,017 INFO [cmd] tar -czvf /results/gatling//authn-users-100.tar.gz --exclude=simulation.log * 2024-04-14 18:38:32,017 INFO /results/gatling 2024-04-14 18:38:32,017 INFO 2024-04-14 18:38:32,017 INFO ---------------------- 2024-04-14 18:38:32,017 INFO [14/Apr/2024 19:36:32] Update /results/gatling//latest 2024-04-14 18:38:32,017 INFO ---------------------- 2024-04-14 18:38:32,017 INFO 2024-04-14 18:38:32,017 INFO ---------------------- 2024-04-14 18:38:32,017 INFO [14/Apr/2024 19:36:32] Update /results/gatling//latest.tar.gz 2024-04-14 18:38:32,017 INFO ---------------------- 2024-04-14 18:38:32,017 INFO ________________________________________________________________________________ [2024-04-14 18:38:32] authn-users-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-14 18:38:32,017 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-100/stderr.txt is small (0 lines), printing all lines: 2024-04-14 18:38:32,017 DEBUG ----- output ----- 2024-04-14 18:38:32,017 DEBUG 2024-04-14 18:38:32,017 INFO ________________________________________________________________________________ [2024-04-14 18:38:32] authn-users-100 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 18:38:32] authn-users-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-14 18:38:32] authn-users-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped