--Task-- name: benchmark-150 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: ['benchmark-130'] preceding_task: benchmark-130 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self150-', 'testCase': 'idc.benchmark.CH2023', 'testMode': 'open', 'throughput': 150} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-07-18 12:57:47] benchmark-150 pre : N/A ________________________________________________________________________________ 2024-07-18 12:57:47 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-07-18 12:57:47 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-150/stdout.txt Pod log : /results/orders/benchmark-150 2024-07-18 12:57:47,271 INFO 2024-07-18 12:57:47,271 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-07-18 12:57:47,271 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:47,594 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:47,595 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:47,595 DEBUG --- stdout --- 2024-07-18 12:57:47,595 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:47,595 DEBUG --- stderr --- 2024-07-18 12:57:47,595 DEBUG ________________________________________________________________________________ [2024-07-18 12:57:47] benchmark-150 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-07-18 12:57:47,596 INFO 2024-07-18 12:57:47,596 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-07-18 12:57:47,596 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:47,917 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:47,917 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:47,917 DEBUG --- stdout --- 2024-07-18 12:57:47,917 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:47,917 DEBUG --- stderr --- 2024-07-18 12:57:47,917 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 "idc.benchmark.CH2023@ema-perf-benchmark" --results-folder /results/gatling/benchmark-150 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-07-18 12:57:47] benchmark-150 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-07-18 12:57:47,918 INFO 2024-07-18 12:57:47,918 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-07-18 12:57:47,918 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:48,234 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:48,235 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:48,235 DEBUG --- stdout --- 2024-07-18 12:57:48,235 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:48,235 DEBUG --- stderr --- 2024-07-18 12:57:48,235 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/order.json { "name": "benchmark-150", "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 \"idc.benchmark.CH2023@ema-perf-benchmark\" --results-folder /results/gatling/benchmark-150 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/benchmark-150", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=0e26551c0104b88 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self150- -Drealm=alpha -DtestCase=idc.benchmark.CH2023 -DtestMode=open -Dthroughput=150 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-07-18 12:57:48] benchmark-150 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-07-18 12:57:48,238 INFO 2024-07-18 12:57:48,238 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-07-18 12:57:48,238 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-18 12:57:48,493 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 12:57:48,493 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:48,493 DEBUG --- stdout --- 2024-07-18 12:57:48,493 DEBUG WAITING 2024-07-18 12:57:48,493 DEBUG --- stderr --- 2024-07-18 12:57:48,493 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-07-18 12:57:48] benchmark-150 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-07-18 12:57:48,494 INFO 2024-07-18 12:57:48,494 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-07-18 12:57:48,494 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:48,814 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:48,814 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:48,814 DEBUG --- stdout --- 2024-07-18 12:57:48,815 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:48,815 DEBUG --- stderr --- 2024-07-18 12:57:48,815 DEBUG 2024-07-18 12:57:48,815 INFO 2024-07-18 12:57:48,815 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/order.json overseer-0-7796fc5d9b-ngsxg:/results/orders/order.json 2024-07-18 12:57:48,815 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 12:57:50,184 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:50,184 DEBUG --- stdout --- 2024-07-18 12:57:50,184 DEBUG 2024-07-18 12:57:50,184 DEBUG --- stderr --- 2024-07-18 12:57:50,184 DEBUG Order has been successfully forwarded to overseer 2024-07-18 12:57:50 - INFO: Check if Gatling simulation started successfully 2024-07-18 12:57:50,185 INFO 2024-07-18 12:57:50,185 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-07-18 12:57:50,185 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:50,544 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:50,544 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:50,544 DEBUG --- stdout --- 2024-07-18 12:57:50,544 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:50,544 DEBUG --- stderr --- 2024-07-18 12:57:50,544 DEBUG 2024-07-18 12:57:50,544 INFO 2024-07-18 12:57:50,545 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg -- head -n 25 /results/orders/benchmark-150/stdout.txt | grep "started..." 2024-07-18 12:57:50,545 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-18 12:57:51,308 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-07-18 12:57:57,058 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-07-18 12:57:57,059 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:57,059 DEBUG --- stdout --- 2024-07-18 12:57:57,059 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-07-18 12:57:57,059 DEBUG --- stderr --- 2024-07-18 12:57:57,059 DEBUG ________________________________________________________________________________ [2024-07-18 12:57:57] benchmark-150 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 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-07-18 14:00:07] benchmark-150 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-07-18 14:00:07,138 INFO 2024-07-18 14:00:07,139 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-07-18 14:00:07,139 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:07,503 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:07,503 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:07,503 DEBUG --- stdout --- 2024-07-18 14:00:07,503 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:07,503 DEBUG --- stderr --- 2024-07-18 14:00:07,503 DEBUG 2024-07-18 14:00:07,504 INFO 2024-07-18 14:00:07,504 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg -- rm -f /results/orders/order.json 2024-07-18 14:00:07,504 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 14:00:08,304 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:08,304 DEBUG --- stdout --- 2024-07-18 14:00:08,304 DEBUG 2024-07-18 14:00:08,304 DEBUG --- stderr --- 2024-07-18 14:00:08,305 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-07-18 14:00:08] benchmark-150 download_logs : Download individual order files ________________________________________________________________________________ 2024-07-18 14:00:08 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/order.json 2024-07-18 14:00:08,306 INFO 2024-07-18 14:00:08,306 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-07-18 14:00:08,306 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:08,617 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:08,618 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:08,618 DEBUG --- stdout --- 2024-07-18 14:00:08,618 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:08,618 DEBUG --- stderr --- 2024-07-18 14:00:08,618 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 14:00:08,618 INFO 2024-07-18 14:00:08,618 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-07-18 14:00:08,618 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:08,931 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:08,931 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:08,931 DEBUG --- stdout --- 2024-07-18 14:00:08,931 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:08,931 DEBUG --- stderr --- 2024-07-18 14:00:08,931 DEBUG 2024-07-18 14:00:08,932 INFO 2024-07-18 14:00:08,932 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- bash -c 'if [ -f "/results/orders/benchmark-150/rc.txt" ]; then echo "/results/orders/benchmark-150/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-150/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-150/rc.txt file found" 2024-07-18 14:00:08,932 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 14:00:09,753 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 14:00:09,754 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:09,754 DEBUG --- stdout --- 2024-07-18 14:00:09,754 DEBUG /results/orders/benchmark-150/rc.txt file found 2024-07-18 14:00:09,754 DEBUG --- stderr --- 2024-07-18 14:00:09,754 DEBUG 2024-07-18 14:00:09,754 INFO 2024-07-18 14:00:09,754 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-07-18 14:00:09,754 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:10,074 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:10,075 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:10,075 DEBUG --- stdout --- 2024-07-18 14:00:10,075 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:10,075 DEBUG --- stderr --- 2024-07-18 14:00:10,075 DEBUG 2024-07-18 14:00:10,075 INFO 2024-07-18 14:00:10,075 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- du -k /results/orders/benchmark-150/rc.txt 2024-07-18 14:00:10,900 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 14:00:10,900 DEBUG --- stdout --- 2024-07-18 14:00:10,901 DEBUG 4 /results/orders/benchmark-150/rc.txt 2024-07-18 14:00:10,901 DEBUG --- stderr --- 2024-07-18 14:00:10,901 DEBUG 2024-07-18 14:00:10,901 INFO - Download file (size 4k) 2024-07-18 14:00:10,901 INFO 2024-07-18 14:00:10,901 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 14:00:10,901 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:11,232 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:11,233 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:11,233 DEBUG --- stdout --- 2024-07-18 14:00:11,233 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:11,233 DEBUG --- stderr --- 2024-07-18 14:00:11,233 DEBUG source : /results/orders/benchmark-150/rc.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/rc.txt 2024-07-18 14:00:11,233 INFO 2024-07-18 14:00:11,233 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-07-18 14:00:11,233 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:11,561 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:11,562 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:11,562 DEBUG --- stdout --- 2024-07-18 14:00:11,562 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:11,562 DEBUG --- stderr --- 2024-07-18 14:00:11,562 DEBUG 2024-07-18 14:00:11,563 INFO 2024-07-18 14:00:11,563 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-150/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/rc.txt 2024-07-18 14:00:11,563 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 14:00:12,317 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:12,317 DEBUG --- stdout --- 2024-07-18 14:00:12,317 DEBUG tar: Removing leading `/' from member names 2024-07-18 14:00:12,318 DEBUG --- stderr --- 2024-07-18 14:00:12,318 DEBUG 2024-07-18 14:00:12,318 INFO 2024-07-18 14:00:12,319 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-07-18 14:00:12,319 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:12,641 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:12,642 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:12,642 DEBUG --- stdout --- 2024-07-18 14:00:12,642 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:12,642 DEBUG --- stderr --- 2024-07-18 14:00:12,642 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 14:00:12,642 INFO 2024-07-18 14:00:12,642 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-07-18 14:00:12,642 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:12,975 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:12,975 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:12,975 DEBUG --- stdout --- 2024-07-18 14:00:12,975 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:12,975 DEBUG --- stderr --- 2024-07-18 14:00:12,975 DEBUG 2024-07-18 14:00:12,976 INFO 2024-07-18 14:00:12,976 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- bash -c 'if [ -f "/results/orders/benchmark-150/stderr.txt" ]; then echo "/results/orders/benchmark-150/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-150/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-150/stderr.txt file found" 2024-07-18 14:00:12,976 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 14:00:13,780 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 14:00:13,781 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:13,781 DEBUG --- stdout --- 2024-07-18 14:00:13,781 DEBUG /results/orders/benchmark-150/stderr.txt file found 2024-07-18 14:00:13,781 DEBUG --- stderr --- 2024-07-18 14:00:13,781 DEBUG 2024-07-18 14:00:13,781 INFO 2024-07-18 14:00:13,781 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-07-18 14:00:13,781 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:14,109 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:14,109 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:14,109 DEBUG --- stdout --- 2024-07-18 14:00:14,109 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:14,109 DEBUG --- stderr --- 2024-07-18 14:00:14,109 DEBUG 2024-07-18 14:00:14,109 INFO 2024-07-18 14:00:14,109 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- du -k /results/orders/benchmark-150/stderr.txt 2024-07-18 14:00:14,963 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 14:00:14,963 DEBUG --- stdout --- 2024-07-18 14:00:14,964 DEBUG 0 /results/orders/benchmark-150/stderr.txt 2024-07-18 14:00:14,964 DEBUG --- stderr --- 2024-07-18 14:00:14,965 DEBUG 2024-07-18 14:00:14,965 INFO - Download file (size 0k) 2024-07-18 14:00:14,965 INFO 2024-07-18 14:00:14,965 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-07-18 14:00:14,965 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:15,273 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:15,274 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:15,274 DEBUG --- stdout --- 2024-07-18 14:00:15,274 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:15,274 DEBUG --- stderr --- 2024-07-18 14:00:15,274 DEBUG source : /results/orders/benchmark-150/stderr.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/stderr.txt 2024-07-18 14:00:15,275 INFO 2024-07-18 14:00:15,275 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-07-18 14:00:15,275 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:15,600 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:15,600 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:15,600 DEBUG --- stdout --- 2024-07-18 14:00:15,601 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:15,601 DEBUG --- stderr --- 2024-07-18 14:00:15,601 DEBUG 2024-07-18 14:00:15,601 INFO 2024-07-18 14:00:15,601 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-150/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/stderr.txt 2024-07-18 14:00:15,601 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 14:00:16,394 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:16,394 DEBUG --- stdout --- 2024-07-18 14:00:16,394 DEBUG tar: Removing leading `/' from member names 2024-07-18 14:00:16,394 DEBUG --- stderr --- 2024-07-18 14:00:16,394 DEBUG 2024-07-18 14:00:16,395 INFO 2024-07-18 14:00:16,395 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-07-18 14:00:16,395 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:16,710 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:16,710 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:16,711 DEBUG --- stdout --- 2024-07-18 14:00:16,711 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:16,711 DEBUG --- stderr --- 2024-07-18 14:00:16,711 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 14:00:16,711 INFO 2024-07-18 14:00:16,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-07-18 14:00:16,711 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:17,018 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:17,019 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:17,019 DEBUG --- stdout --- 2024-07-18 14:00:17,019 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:17,019 DEBUG --- stderr --- 2024-07-18 14:00:17,019 DEBUG 2024-07-18 14:00:17,020 INFO 2024-07-18 14:00:17,020 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- bash -c 'if [ -f "/results/orders/benchmark-150/stdout.txt" ]; then echo "/results/orders/benchmark-150/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-150/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-150/stdout.txt file found" 2024-07-18 14:00:17,020 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 14:00:17,835 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 14:00:17,835 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:17,835 DEBUG --- stdout --- 2024-07-18 14:00:17,835 DEBUG /results/orders/benchmark-150/stdout.txt file found 2024-07-18 14:00:17,835 DEBUG --- stderr --- 2024-07-18 14:00:17,836 DEBUG 2024-07-18 14:00:17,836 INFO 2024-07-18 14:00:17,836 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-07-18 14:00:17,836 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:18,139 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:18,139 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:18,139 DEBUG --- stdout --- 2024-07-18 14:00:18,139 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:18,139 DEBUG --- stderr --- 2024-07-18 14:00:18,139 DEBUG 2024-07-18 14:00:18,139 INFO 2024-07-18 14:00:18,139 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- du -k /results/orders/benchmark-150/stdout.txt 2024-07-18 14:00:18,947 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 14:00:18,947 DEBUG --- stdout --- 2024-07-18 14:00:18,948 DEBUG 4308 /results/orders/benchmark-150/stdout.txt 2024-07-18 14:00:18,948 DEBUG --- stderr --- 2024-07-18 14:00:18,948 DEBUG 2024-07-18 14:00:18,948 INFO - Download file (size 4308k) 2024-07-18 14:00:18,948 INFO 2024-07-18 14:00:18,948 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-07-18 14:00:18,948 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:19,255 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:19,255 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:19,255 DEBUG --- stdout --- 2024-07-18 14:00:19,255 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:19,255 DEBUG --- stderr --- 2024-07-18 14:00:19,256 DEBUG source : /results/orders/benchmark-150/stdout.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/stdout.txt 2024-07-18 14:00:19,256 INFO 2024-07-18 14:00:19,256 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 14:00:19,256 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:19,576 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:19,576 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:19,576 DEBUG --- stdout --- 2024-07-18 14:00:19,576 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:19,577 DEBUG --- stderr --- 2024-07-18 14:00:19,577 DEBUG 2024-07-18 14:00:19,578 INFO 2024-07-18 14:00:19,578 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-150/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/stdout.txt 2024-07-18 14:00:19,578 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 14:00:20,857 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:20,858 DEBUG --- stdout --- 2024-07-18 14:00:20,858 DEBUG tar: Removing leading `/' from member names 2024-07-18 14:00:20,858 DEBUG --- stderr --- 2024-07-18 14:00:20,858 DEBUG ________________________________________________________________________________ [2024-07-18 14:00:20] benchmark-150 show_cmd : Display order command ________________________________________________________________________________ 2024-07-18 14:00:20,859 INFO 2024-07-18 14:00:20,859 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 14:00:20,859 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:21,197 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:21,197 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:21,197 DEBUG --- stdout --- 2024-07-18 14:00:21,197 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:21,197 DEBUG --- stderr --- 2024-07-18 14:00:21,197 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 14:00:21,197 INFO 2024-07-18 14:00:21,198 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-07-18 14:00:21,198 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:21,526 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:21,526 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:21,526 DEBUG --- stdout --- 2024-07-18 14:00:21,526 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:21,526 DEBUG --- stderr --- 2024-07-18 14:00:21,526 DEBUG 2024-07-18 14:00:21,527 INFO 2024-07-18 14:00:21,527 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- bash -c 'if [ -f "/results/orders/benchmark-150/cmd.txt" ]; then echo "/results/orders/benchmark-150/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-150/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-150/cmd.txt file found" 2024-07-18 14:00:21,527 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 14:00:22,361 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 14:00:22,362 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:22,362 DEBUG --- stdout --- 2024-07-18 14:00:22,362 DEBUG /results/orders/benchmark-150/cmd.txt file found 2024-07-18 14:00:22,362 DEBUG --- stderr --- 2024-07-18 14:00:22,362 DEBUG 2024-07-18 14:00:22,362 INFO 2024-07-18 14:00:22,362 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-07-18 14:00:22,362 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:22,672 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:22,672 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:22,672 DEBUG --- stdout --- 2024-07-18 14:00:22,672 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:22,672 DEBUG --- stderr --- 2024-07-18 14:00:22,672 DEBUG 2024-07-18 14:00:22,672 INFO 2024-07-18 14:00:22,673 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- du -k /results/orders/benchmark-150/cmd.txt 2024-07-18 14:00:23,491 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 14:00:23,491 DEBUG --- stdout --- 2024-07-18 14:00:23,491 DEBUG 4 /results/orders/benchmark-150/cmd.txt 2024-07-18 14:00:23,491 DEBUG --- stderr --- 2024-07-18 14:00:23,491 DEBUG 2024-07-18 14:00:23,491 INFO - Download file (size 4k) 2024-07-18 14:00:23,492 INFO 2024-07-18 14:00:23,492 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-07-18 14:00:23,492 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:23,803 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:23,803 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:23,803 DEBUG --- stdout --- 2024-07-18 14:00:23,803 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:23,803 DEBUG --- stderr --- 2024-07-18 14:00:23,803 DEBUG source : /results/orders/benchmark-150/cmd.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/cmd.txt 2024-07-18 14:00:23,804 INFO 2024-07-18 14:00:23,804 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-07-18 14:00:23,804 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 14:00:24,113 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 14:00:24,113 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:24,113 DEBUG --- stdout --- 2024-07-18 14:00:24,113 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 14:00:24,113 DEBUG --- stderr --- 2024-07-18 14:00:24,113 DEBUG 2024-07-18 14:00:24,113 INFO 2024-07-18 14:00:24,113 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-150/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/cmd.txt 2024-07-18 14:00:24,114 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 14:00:24,860 INFO [loop_until]: OK (rc = 0) 2024-07-18 14:00:24,860 DEBUG --- stdout --- 2024-07-18 14:00:24,860 DEBUG tar: Removing leading `/' from member names 2024-07-18 14:00:24,860 DEBUG --- stderr --- 2024-07-18 14:00:24,860 DEBUG 2024-07-18 14:00:24,860 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/cmd.txt is small (1 lines), printing all lines: 2024-07-18 14:00:24,861 DEBUG ----- output ----- 2024-07-18 14:00:24,861 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 "idc.benchmark.CH2023@ema-perf-benchmark" --results-folder /results/gatling/benchmark-150 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-07-18 14:00:24,861 INFO ________________________________________________________________________________ [2024-07-18 14:00:24] benchmark-150 show_rc : Display rc order ________________________________________________________________________________ 2024-07-18 14:00:24,861 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/rc.txt is small (1 lines), printing all lines: 2024-07-18 14:00:24,861 DEBUG ----- output ----- 2024-07-18 14:00:24,861 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-07-18 14:00:24,861 INFO ________________________________________________________________________________ [2024-07-18 14:00:24] benchmark-150 show_stdout : Display stdout order ________________________________________________________________________________ 2024-07-18 14:00:24,871 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/stdout.txt is 52797 lines): 2024-07-18 14:00:24,871 DEBUG ----- output ----- 2024-07-18 14:00:24,871 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-07-18 14:00:24,871 INFO Gatling 3.11.5 is available! (you're using 3.9.5) 2024-07-18 14:00:24,871 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-07-18 14:00:24,871 INFO 2024-07-18 14:00:24,871 INFO ================================================================================ 2024-07-18 14:00:24,871 INFO 2024-07-18 13:57:59 5s elapsed 2024-07-18 14:00:24,871 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 14:00:24,871 INFO > Global (OK=433 KO=0 ) 2024-07-18 14:00:24,871 INFO > restLoginInitiate (OK=50 KO=0 ) 2024-07-18 14:00:24,871 INFO > restLoginUsernamePasswordCallback (OK=48 KO=0 ) 2024-07-18 14:00:24,871 INFO > Skip 2FA (OK=33 KO=0 ) 2024-07-18 14:00:24,871 INFO > User Authorize PKCE stage (OK=50 KO=0 ) 2024-07-18 14:00:24,871 INFO > User AccessToken PKCE stage (OK=48 KO=0 ) 2024-07-18 14:00:24,871 INFO > getIdToken (OK=6 KO=0 ) 2024-07-18 14:00:24,871 INFO > getIdToken Redirect 1 (OK=6 KO=0 ) 2024-07-18 14:00:24,871 INFO > createOrganization (OK=5 KO=0 ) 2024-07-18 14:00:24,872 INFO > queryOrganization (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > deleteOrganization (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI-Post-Login (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI-Post-Login Redirect 1 (OK=12 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI AM idFromSession (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI AM validateGoto (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI Enduser appAuthHelper2 (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI Enduser appAuthHelper1 (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI Enduser sessioncheck (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI IDM uiconfig (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI IDM version (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI IDM features (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI IDM privilege (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI IDM login (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI IDM alpha_user (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI IDM user (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI IDM dashboard (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI IDM oidcToken (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > registration_restLoginInitiate (OK=12 KO=0 ) 2024-07-18 14:00:24,872 INFO > userinfo (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI-Logout Redirect 1 (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI AM revoke1 (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI AM endsession (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > UI AM revoke2 (OK=4 KO=0 ) 2024-07-18 14:00:24,872 INFO > RootUserinfo (OK=54 KO=0 ) 2024-07-18 14:00:24,872 INFO > registrationCallback (OK=12 KO=0 ) 2024-07-18 14:00:24,872 INFO > tokenUserinfo (OK=9 KO=0 ) 2024-07-18 14:00:24,873 INFO 2024-07-18 14:00:24,873 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 14:00:24,873 INFO [- ] 0% 2024-07-18 14:00:24,873 INFO waiting: 542453 / active: 32 / done: 31 2024-07-18 14:00:24,873 INFO ================================================================================ 2024-07-18 14:00:24,873 INFO 2024-07-18 14:00:24,873 INFO 13:58:01.437 [ERROR] i.g.h.a.HttpRequestAction - 'tokenUserinfo' failed to execute: No attribute named 'access_token' is defined 2024-07-18 14:00:24,873 INFO 2024-07-18 14:00:24,873 INFO ================================================================================ 2024-07-18 14:00:24,873 INFO 2024-07-18 13:58:04 10s elapsed 2024-07-18 14:00:24,873 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 14:00:24,873 INFO > Global (OK=1765 KO=0 ) 2024-07-18 14:00:24,873 INFO > restLoginInitiate (OK=211 KO=0 ) 2024-07-18 14:00:24,873 INFO > restLoginUsernamePasswordCallback (OK=207 KO=0 ) 2024-07-18 14:00:24,873 INFO > Skip 2FA (OK=157 KO=0 ) 2024-07-18 14:00:24,873 INFO > User Authorize PKCE stage (OK=207 KO=0 ) 2024-07-18 14:00:24,873 INFO > User AccessToken PKCE stage (OK=204 KO=0 ) 2024-07-18 14:00:24,873 INFO > getIdToken (OK=33 KO=0 ) 2024-07-18 14:00:24,873 INFO > getIdToken Redirect 1 (OK=33 KO=0 ) 2024-07-18 14:00:24,873 INFO > createOrganization (OK=33 KO=0 ) 2024-07-18 14:00:24,873 INFO > queryOrganization (OK=31 KO=0 ) 2024-07-18 14:00:24,873 INFO > deleteOrganization (OK=31 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI-Post-Login (OK=7 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI-Post-Login Redirect 1 (OK=20 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI AM idFromSession (OK=7 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI AM validateGoto (OK=7 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI Enduser appAuthHelper2 (OK=7 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI Enduser appAuthHelper1 (OK=7 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI Enduser sessioncheck (OK=6 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI IDM uiconfig (OK=6 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI IDM version (OK=6 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI IDM features (OK=6 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI IDM privilege (OK=6 KO=0 ) 2024-07-18 14:00:24,873 INFO > UI IDM login (OK=6 KO=0 ) 2024-07-18 14:00:24,874 INFO > UI IDM alpha_user (OK=6 KO=0 ) 2024-07-18 14:00:24,874 INFO > UI IDM user (OK=6 KO=0 ) 2024-07-18 14:00:24,874 INFO > UI IDM dashboard (OK=6 KO=0 ) 2024-07-18 14:00:24,874 INFO > UI IDM oidcToken (OK=6 KO=0 ) 2024-07-18 14:00:24,874 INFO > registration_restLoginInitiate (OK=38 KO=0 ) 2024-07-18 14:00:24,874 INFO > userinfo (OK=6 KO=0 ) 2024-07-18 14:00:24,874 INFO > UI-Logout Redirect 1 (OK=6 KO=0 ) 2024-07-18 14:00:24,874 INFO > UI AM revoke1 (OK=6 KO=0 ) 2024-07-18 14:00:24,874 INFO > UI AM endsession (OK=6 KO=0 ) 2024-07-18 14:00:24,874 INFO > UI AM revoke2 (OK=6 KO=0 ) 2024-07-18 14:00:24,874 INFO > RootUserinfo (OK=359 KO=0 ) 2024-07-18 14:00:24,874 INFO > registrationCallback (OK=37 KO=0 ) 2024-07-18 14:00:24,874 INFO > tokenUserinfo (OK=38 KO=0 ) 2024-07-18 14:00:24,874 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-07-18 14:00:24,874 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 14:00:24,874 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 1 (100.0%) 2024-07-18 14:00:24,874 INFO cess_token' is defined 2024-07-18 14:00:24,874 INFO 2024-07-18 14:00:24,874 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 14:00:24,874 INFO [- ] 0% 2024-07-18 14:00:24,874 INFO waiting: 542265 / active: 114 / done: 137 2024-07-18 14:00:24,874 INFO ================================================================================ 2024-07-18 14:00:24,874 INFO 2024-07-18 14:00:24,874 INFO [...] 2024-07-18 14:00:24,889 INFO > Global (OK=4571788 KO=43 ) 2024-07-18 14:00:24,889 INFO > restLoginInitiate (OK=455157 KO=6 ) 2024-07-18 14:00:24,889 INFO > restLoginUsernamePasswordCallback (OK=455152 KO=5 ) 2024-07-18 14:00:24,889 INFO > Skip 2FA (OK=324837 KO=3 ) 2024-07-18 14:00:24,889 INFO > User Authorize PKCE stage (OK=468838 KO=5 ) 2024-07-18 14:00:24,889 INFO > User AccessToken PKCE stage (OK=468833 KO=5 ) 2024-07-18 14:00:24,889 INFO > getIdToken (OK=65057 KO=0 ) 2024-07-18 14:00:24,889 INFO > getIdToken Redirect 1 (OK=65057 KO=0 ) 2024-07-18 14:00:24,889 INFO > createOrganization (OK=65055 KO=0 ) 2024-07-18 14:00:24,889 INFO > queryOrganization (OK=65055 KO=0 ) 2024-07-18 14:00:24,889 INFO > deleteOrganization (OK=65055 KO=0 ) 2024-07-18 14:00:24,889 INFO > UI-Post-Login (OK=21647 KO=0 ) 2024-07-18 14:00:24,889 INFO > UI-Post-Login Redirect 1 (OK=64941 KO=0 ) 2024-07-18 14:00:24,889 INFO > UI AM idFromSession (OK=21646 KO=1 ) 2024-07-18 14:00:24,889 INFO > UI AM validateGoto (OK=21645 KO=2 ) 2024-07-18 14:00:24,889 INFO > UI Enduser appAuthHelper2 (OK=21647 KO=0 ) 2024-07-18 14:00:24,889 INFO > UI Enduser appAuthHelper1 (OK=21647 KO=0 ) 2024-07-18 14:00:24,889 INFO > UI Enduser sessioncheck (OK=21647 KO=0 ) 2024-07-18 14:00:24,889 INFO > UI IDM uiconfig (OK=21647 KO=0 ) 2024-07-18 14:00:24,889 INFO > UI IDM version (OK=21647 KO=0 ) 2024-07-18 14:00:24,890 INFO > UI IDM features (OK=21646 KO=0 ) 2024-07-18 14:00:24,890 INFO > UI IDM privilege (OK=21646 KO=0 ) 2024-07-18 14:00:24,890 INFO > UI IDM login (OK=21646 KO=0 ) 2024-07-18 14:00:24,890 INFO > UI IDM alpha_user (OK=21645 KO=1 ) 2024-07-18 14:00:24,890 INFO > UI IDM user (OK=21646 KO=0 ) 2024-07-18 14:00:24,890 INFO > UI IDM dashboard (OK=21646 KO=0 ) 2024-07-18 14:00:24,890 INFO > UI IDM oidcToken (OK=21645 KO=2 ) 2024-07-18 14:00:24,890 INFO > registration_restLoginInitiate (OK=87353 KO=0 ) 2024-07-18 14:00:24,890 INFO > userinfo (OK=21646 KO=0 ) 2024-07-18 14:00:24,890 INFO > UI-Logout Redirect 1 (OK=21647 KO=0 ) 2024-07-18 14:00:24,890 INFO > UI AM revoke1 (OK=21646 KO=0 ) 2024-07-18 14:00:24,890 INFO > UI AM endsession (OK=21645 KO=0 ) 2024-07-18 14:00:24,890 INFO > UI AM revoke2 (OK=21645 KO=1 ) 2024-07-18 14:00:24,890 INFO > RootUserinfo (OK=1298871 KO=9 ) 2024-07-18 14:00:24,890 INFO > registrationCallback (OK=87350 KO=3 ) 2024-07-18 14:00:24,890 INFO > tokenUserinfo (OK=100710 KO=0 ) 2024-07-18 14:00:24,890 INFO > restloginProgressiveProfileCallback (OK=1545 KO=0 ) 2024-07-18 14:00:24,890 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 14:00:24,890 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 7956 (98.82%) 2024-07-18 14:00:24,890 INFO cess_token' is defined 2024-07-18 14:00:24,890 INFO > RootUserinfo: Failed to build request: No attribute named 'acc 35 ( 0.43%) 2024-07-18 14:00:24,890 INFO ess_token' is defined 2024-07-18 14:00:24,890 INFO > status.find.in([200, 209], 304), found 502 21 ( 0.26%) 2024-07-18 14:00:24,890 INFO > status.find.is(200), but actually found 502 14 ( 0.17%) 2024-07-18 14:00:24,890 INFO > status.find.is(302), but actually found 502 5 ( 0.06%) 2024-07-18 14:00:24,890 INFO > UI AM endsession: Failed to build request: No attribute named 2 ( 0.02%) 2024-07-18 14:00:24,890 INFO 'id_token_2' is defined 2024-07-18 14:00:24,890 INFO > createOrganization: Failed to build request: No attribute name 2 ( 0.02%) 2024-07-18 14:00:24,890 INFO d 'access_token' is defined 2024-07-18 14:00:24,890 INFO > queryOrganization: Failed to build request: No attribute named 2 ( 0.02%) 2024-07-18 14:00:24,890 INFO 'organization_id' is defined 2024-07-18 14:00:24,890 INFO > deleteOrganization: Failed to build request: No attribute name 2 ( 0.02%) 2024-07-18 14:00:24,890 INFO d 'organization_id' is defined 2024-07-18 14:00:24,890 INFO > status.find.in([200, 209], 304), found 401 1 ( 0.01%) 2024-07-18 14:00:24,890 INFO > UI IDM features: Failed to build request: No attribute named 1 ( 0.01%) 2024-07-18 14:00:24,890 INFO 'access_token_2' is defined 2024-07-18 14:00:24,891 INFO > UI IDM login: Failed to build request: No attribute named 'ac 1 ( 0.01%) 2024-07-18 14:00:24,891 INFO cess_token_2' is defined 2024-07-18 14:00:24,891 INFO > UI IDM privilege: Failed to build request: No attribute named 1 ( 0.01%) 2024-07-18 14:00:24,891 INFO 'access_token_2' is defined 2024-07-18 14:00:24,891 INFO > UI IDM alpha_user: Failed to build request: No attribute name 1 ( 0.01%) 2024-07-18 14:00:24,891 INFO d 'access_token_2' is defined 2024-07-18 14:00:24,891 INFO > UI IDM user: Failed to build request: No attribute named 'use 1 ( 0.01%) 2024-07-18 14:00:24,891 INFO r_uuid' is defined 2024-07-18 14:00:24,891 INFO > UI IDM dashboard: Failed to build request: No attribute named 1 ( 0.01%) 2024-07-18 14:00:24,891 INFO 'access_token_2' is defined 2024-07-18 14:00:24,891 INFO > headerRegex((location,#id_token=(.*))).find.exists, found noth 1 ( 0.01%) 2024-07-18 14:00:24,891 INFO ing 2024-07-18 14:00:24,891 INFO > userinfo: Failed to build request: No attribute named 'access_ 1 ( 0.01%) 2024-07-18 14:00:24,891 INFO token' is defined 2024-07-18 14:00:24,891 INFO > UI AM revoke1: Failed to build request: No attribute named 'a 1 ( 0.01%) 2024-07-18 14:00:24,891 INFO ccess_token' is defined 2024-07-18 14:00:24,891 INFO > UI AM revoke2: Failed to build request: No attribute named 'a 1 ( 0.01%) 2024-07-18 14:00:24,891 INFO ccess_token_2' is defined 2024-07-18 14:00:24,891 INFO > status.find.is(302), but actually found 503 1 ( 0.01%) 2024-07-18 14:00:24,891 INFO 2024-07-18 14:00:24,891 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 14:00:24,891 INFO [##########################################################################]100% 2024-07-18 14:00:24,891 INFO waiting: 0 / active: 0 / done: 542516 2024-07-18 14:00:24,891 INFO ================================================================================ 2024-07-18 14:00:24,891 INFO 2024-07-18 14:00:24,891 INFO Simulation com.forgerock.gatling.Loadtest completed in 3638 seconds 2024-07-18 14:00:24,891 INFO 2024-07-18 14:00:24,891 INFO ---------------------- 2024-07-18 14:00:24,891 INFO [18/Jul/2024 14:58:32] Create tar file benchmark-150.tar.gz 2024-07-18 14:00:24,891 INFO ---------------------- 2024-07-18 14:00:24,891 INFO RESULTS_FOLDER=/results/gatling/ 2024-07-18 14:00:24,891 INFO RESULT_FOLDER=benchmark-150 2024-07-18 14:00:24,891 INFO SIMULATION_PATH=benchmark-150/loadtest-20240718125753943 2024-07-18 14:00:24,891 INFO PWD=/results/gatling/benchmark-150/loadtest-20240718125753943 2024-07-18 14:00:24,891 INFO [cmd] tar -czvf /results/gatling//benchmark-150.tar.gz --exclude=simulation.log * 2024-07-18 14:00:24,891 INFO /results/gatling 2024-07-18 14:00:24,891 INFO 2024-07-18 14:00:24,891 INFO ---------------------- 2024-07-18 14:00:24,891 INFO [18/Jul/2024 14:58:32] Update /results/gatling//latest 2024-07-18 14:00:24,891 INFO ---------------------- 2024-07-18 14:00:24,891 INFO 2024-07-18 14:00:24,891 INFO ---------------------- 2024-07-18 14:00:24,892 INFO [18/Jul/2024 14:58:32] Update /results/gatling//latest.tar.gz 2024-07-18 14:00:24,892 INFO ---------------------- 2024-07-18 14:00:24,892 INFO ________________________________________________________________________________ [2024-07-18 14:00:24] benchmark-150 show_stderr : Display stderr order ________________________________________________________________________________ 2024-07-18 14:00:24,892 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/08_benchmark-150/stderr.txt is small (0 lines), printing all lines: 2024-07-18 14:00:24,892 DEBUG ----- output ----- 2024-07-18 14:00:24,892 DEBUG 2024-07-18 14:00:24,892 INFO ________________________________________________________________________________ [2024-07-18 14:00:24] benchmark-150 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-07-18 14:00:24] benchmark-150 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-07-18 14:00:24] benchmark-150 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped