--Task-- name: benchmark-90 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-70'] preceding_task: benchmark-70 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self90-', 'testCase': 'idc.benchmark.CH2023', 'testMode': 'open', 'throughput': 90} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-07-18 09:49:51] benchmark-90 pre : N/A ________________________________________________________________________________ 2024-07-18 09:49:51 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-07-18 09:49:51 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-90/stdout.txt Pod log : /results/orders/benchmark-90 2024-07-18 09:49:51,264 INFO 2024-07-18 09:49:51,264 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 09:49:51,264 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:51,586 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:51,586 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:51,586 DEBUG --- stdout --- 2024-07-18 09:49:51,586 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:51,586 DEBUG --- stderr --- 2024-07-18 09:49:51,586 DEBUG ________________________________________________________________________________ [2024-07-18 09:49:51] benchmark-90 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-07-18 09:49:51,587 INFO 2024-07-18 09:49:51,587 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 09:49:51,587 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:51,902 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:51,902 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:51,902 DEBUG --- stdout --- 2024-07-18 09:49:51,902 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:51,902 DEBUG --- stderr --- 2024-07-18 09:49:51,902 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-90 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-07-18 09:49:51] benchmark-90 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-07-18 09:49:51,903 INFO 2024-07-18 09:49:51,903 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 09:49:51,903 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:52,220 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:52,220 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:52,221 DEBUG --- stdout --- 2024-07-18 09:49:52,221 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:52,221 DEBUG --- stderr --- 2024-07-18 09:49:52,221 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/order.json { "name": "benchmark-90", "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-90 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/benchmark-90", "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=self90- -Drealm=alpha -DtestCase=idc.benchmark.CH2023 -DtestMode=open -Dthroughput=90 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-07-18 09:49:52] benchmark-90 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 09:49:52,224 INFO 2024-07-18 09:49:52,224 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-07-18 09:49:52,224 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-18 09:49:52,475 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 09:49:52,475 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:52,475 DEBUG --- stdout --- 2024-07-18 09:49:52,475 DEBUG WAITING 2024-07-18 09:49:52,475 DEBUG --- stderr --- 2024-07-18 09:49:52,475 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 09:49:52] benchmark-90 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-07-18 09:49:52,476 INFO 2024-07-18 09:49:52,476 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 09:49:52,476 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:52,779 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:52,779 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:52,779 DEBUG --- stdout --- 2024-07-18 09:49:52,779 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:52,779 DEBUG --- stderr --- 2024-07-18 09:49:52,779 DEBUG 2024-07-18 09:49:52,779 INFO 2024-07-18 09:49:52,779 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/05_benchmark-90/order.json overseer-0-7796fc5d9b-ngsxg:/results/orders/order.json 2024-07-18 09:49:52,779 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 09:49:54,145 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:54,145 DEBUG --- stdout --- 2024-07-18 09:49:54,145 DEBUG 2024-07-18 09:49:54,145 DEBUG --- stderr --- 2024-07-18 09:49:54,145 DEBUG Order has been successfully forwarded to overseer 2024-07-18 09:49:54 - INFO: Check if Gatling simulation started successfully 2024-07-18 09:49:54,146 INFO 2024-07-18 09:49:54,146 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 09:49:54,146 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:54,499 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:54,499 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:54,499 DEBUG --- stdout --- 2024-07-18 09:49:54,499 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:54,499 DEBUG --- stderr --- 2024-07-18 09:49:54,499 DEBUG 2024-07-18 09:49:54,499 INFO 2024-07-18 09:49:54,499 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-90/stdout.txt | grep "started..." 2024-07-18 09:49:54,499 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-18 09:49:55,255 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-07-18 09:50:01,040 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-07-18 09:50:01,041 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:50:01,041 DEBUG --- stdout --- 2024-07-18 09:50:01,041 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 09:50:01,041 DEBUG --- stderr --- 2024-07-18 09:50:01,041 DEBUG ________________________________________________________________________________ [2024-07-18 09:50:01] benchmark-90 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 04s, 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 10:52:11] benchmark-90 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-07-18 10:52:11,194 INFO 2024-07-18 10:52:11,195 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 10:52:11,195 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:11,546 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:11,547 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:11,547 DEBUG --- stdout --- 2024-07-18 10:52:11,547 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:11,547 DEBUG --- stderr --- 2024-07-18 10:52:11,547 DEBUG 2024-07-18 10:52:11,548 INFO 2024-07-18 10:52:11,548 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 10:52:11,548 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 10:52:12,384 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:12,384 DEBUG --- stdout --- 2024-07-18 10:52:12,384 DEBUG 2024-07-18 10:52:12,384 DEBUG --- stderr --- 2024-07-18 10:52:12,384 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-07-18 10:52:12] benchmark-90 download_logs : Download individual order files ________________________________________________________________________________ 2024-07-18 10:52:12 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/order.json 2024-07-18 10:52:12,386 INFO 2024-07-18 10:52:12,386 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 10:52:12,386 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:12,706 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:12,706 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:12,706 DEBUG --- stdout --- 2024-07-18 10:52:12,707 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:12,707 DEBUG --- stderr --- 2024-07-18 10:52:12,707 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 10:52:12,707 INFO 2024-07-18 10:52:12,707 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 10:52:12,707 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:13,020 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:13,020 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:13,020 DEBUG --- stdout --- 2024-07-18 10:52:13,021 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:13,021 DEBUG --- stderr --- 2024-07-18 10:52:13,021 DEBUG 2024-07-18 10:52:13,021 INFO 2024-07-18 10:52:13,021 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-90/rc.txt" ]; then echo "/results/orders/benchmark-90/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-90/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-90/rc.txt file found" 2024-07-18 10:52:13,021 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 10:52:13,840 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 10:52:13,840 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:13,840 DEBUG --- stdout --- 2024-07-18 10:52:13,840 DEBUG /results/orders/benchmark-90/rc.txt file found 2024-07-18 10:52:13,840 DEBUG --- stderr --- 2024-07-18 10:52:13,840 DEBUG 2024-07-18 10:52:13,841 INFO 2024-07-18 10:52:13,841 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 10:52:13,841 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:14,166 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:14,166 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:14,166 DEBUG --- stdout --- 2024-07-18 10:52:14,167 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:14,167 DEBUG --- stderr --- 2024-07-18 10:52:14,167 DEBUG 2024-07-18 10:52:14,167 INFO 2024-07-18 10:52:14,167 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-90/rc.txt 2024-07-18 10:52:14,995 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 10:52:14,996 DEBUG --- stdout --- 2024-07-18 10:52:14,996 DEBUG 4 /results/orders/benchmark-90/rc.txt 2024-07-18 10:52:14,996 DEBUG --- stderr --- 2024-07-18 10:52:14,996 DEBUG 2024-07-18 10:52:14,996 INFO - Download file (size 4k) 2024-07-18 10:52:14,996 INFO 2024-07-18 10:52:14,996 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 10:52:14,996 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:15,316 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:15,316 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:15,316 DEBUG --- stdout --- 2024-07-18 10:52:15,316 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:15,316 DEBUG --- stderr --- 2024-07-18 10:52:15,316 DEBUG source : /results/orders/benchmark-90/rc.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/rc.txt 2024-07-18 10:52:15,317 INFO 2024-07-18 10:52:15,317 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 10:52:15,317 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:15,638 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:15,638 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:15,638 DEBUG --- stdout --- 2024-07-18 10:52:15,638 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:15,638 DEBUG --- stderr --- 2024-07-18 10:52:15,638 DEBUG 2024-07-18 10:52:15,639 INFO 2024-07-18 10:52:15,639 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-90/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/rc.txt 2024-07-18 10:52:15,639 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 10:52:16,433 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:16,434 DEBUG --- stdout --- 2024-07-18 10:52:16,434 DEBUG tar: Removing leading `/' from member names 2024-07-18 10:52:16,434 DEBUG --- stderr --- 2024-07-18 10:52:16,434 DEBUG 2024-07-18 10:52:16,435 INFO 2024-07-18 10:52:16,435 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 10:52:16,435 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:16,792 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:16,793 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:16,793 DEBUG --- stdout --- 2024-07-18 10:52:16,793 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:16,793 DEBUG --- stderr --- 2024-07-18 10:52:16,793 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 10:52:16,793 INFO 2024-07-18 10:52:16,793 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 10:52:16,793 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:17,111 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:17,111 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:17,111 DEBUG --- stdout --- 2024-07-18 10:52:17,111 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:17,111 DEBUG --- stderr --- 2024-07-18 10:52:17,112 DEBUG 2024-07-18 10:52:17,112 INFO 2024-07-18 10:52:17,112 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-90/stderr.txt" ]; then echo "/results/orders/benchmark-90/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-90/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-90/stderr.txt file found" 2024-07-18 10:52:17,112 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 10:52:17,939 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 10:52:17,939 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:17,939 DEBUG --- stdout --- 2024-07-18 10:52:17,939 DEBUG /results/orders/benchmark-90/stderr.txt file found 2024-07-18 10:52:17,939 DEBUG --- stderr --- 2024-07-18 10:52:17,939 DEBUG 2024-07-18 10:52:17,940 INFO 2024-07-18 10:52:17,940 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 10:52:17,940 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:18,247 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:18,247 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:18,247 DEBUG --- stdout --- 2024-07-18 10:52:18,248 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:18,248 DEBUG --- stderr --- 2024-07-18 10:52:18,248 DEBUG 2024-07-18 10:52:18,248 INFO 2024-07-18 10:52:18,248 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-90/stderr.txt 2024-07-18 10:52:19,046 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 10:52:19,047 DEBUG --- stdout --- 2024-07-18 10:52:19,047 DEBUG 0 /results/orders/benchmark-90/stderr.txt 2024-07-18 10:52:19,047 DEBUG --- stderr --- 2024-07-18 10:52:19,047 DEBUG 2024-07-18 10:52:19,047 INFO - Download file (size 0k) 2024-07-18 10:52:19,047 INFO 2024-07-18 10:52:19,047 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 10:52:19,047 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:19,369 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:19,369 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:19,369 DEBUG --- stdout --- 2024-07-18 10:52:19,369 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:19,369 DEBUG --- stderr --- 2024-07-18 10:52:19,369 DEBUG source : /results/orders/benchmark-90/stderr.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/stderr.txt 2024-07-18 10:52:19,369 INFO 2024-07-18 10:52:19,369 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 10:52:19,370 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:19,690 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:19,691 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:19,691 DEBUG --- stdout --- 2024-07-18 10:52:19,691 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:19,691 DEBUG --- stderr --- 2024-07-18 10:52:19,691 DEBUG 2024-07-18 10:52:19,691 INFO 2024-07-18 10:52:19,691 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-90/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/stderr.txt 2024-07-18 10:52:19,691 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 10:52:20,449 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:20,450 DEBUG --- stdout --- 2024-07-18 10:52:20,450 DEBUG tar: Removing leading `/' from member names 2024-07-18 10:52:20,450 DEBUG --- stderr --- 2024-07-18 10:52:20,450 DEBUG 2024-07-18 10:52:20,450 INFO 2024-07-18 10:52:20,450 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 10:52:20,450 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:20,777 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:20,778 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:20,778 DEBUG --- stdout --- 2024-07-18 10:52:20,778 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:20,778 DEBUG --- stderr --- 2024-07-18 10:52:20,778 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 10:52:20,778 INFO 2024-07-18 10:52:20,778 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 10:52:20,778 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:21,113 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:21,113 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:21,114 DEBUG --- stdout --- 2024-07-18 10:52:21,114 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:21,114 DEBUG --- stderr --- 2024-07-18 10:52:21,114 DEBUG 2024-07-18 10:52:21,114 INFO 2024-07-18 10:52:21,114 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-90/stdout.txt" ]; then echo "/results/orders/benchmark-90/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-90/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-90/stdout.txt file found" 2024-07-18 10:52:21,114 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 10:52:21,944 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 10:52:21,944 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:21,944 DEBUG --- stdout --- 2024-07-18 10:52:21,944 DEBUG /results/orders/benchmark-90/stdout.txt file found 2024-07-18 10:52:21,944 DEBUG --- stderr --- 2024-07-18 10:52:21,945 DEBUG 2024-07-18 10:52:21,945 INFO 2024-07-18 10:52:21,945 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 10:52:21,945 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:22,271 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:22,271 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:22,271 DEBUG --- stdout --- 2024-07-18 10:52:22,271 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:22,271 DEBUG --- stderr --- 2024-07-18 10:52:22,271 DEBUG 2024-07-18 10:52:22,271 INFO 2024-07-18 10:52:22,272 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-90/stdout.txt 2024-07-18 10:52:23,095 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 10:52:23,095 DEBUG --- stdout --- 2024-07-18 10:52:23,095 DEBUG 2924 /results/orders/benchmark-90/stdout.txt 2024-07-18 10:52:23,095 DEBUG --- stderr --- 2024-07-18 10:52:23,095 DEBUG 2024-07-18 10:52:23,095 INFO - Download file (size 2924k) 2024-07-18 10:52:23,096 INFO 2024-07-18 10:52:23,096 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 10:52:23,096 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:23,458 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:23,458 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:23,458 DEBUG --- stdout --- 2024-07-18 10:52:23,458 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:23,458 DEBUG --- stderr --- 2024-07-18 10:52:23,458 DEBUG source : /results/orders/benchmark-90/stdout.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/stdout.txt 2024-07-18 10:52:23,459 INFO 2024-07-18 10:52:23,459 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 10:52:23,459 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:23,777 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:23,777 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:23,777 DEBUG --- stdout --- 2024-07-18 10:52:23,777 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:23,777 DEBUG --- stderr --- 2024-07-18 10:52:23,777 DEBUG 2024-07-18 10:52:23,777 INFO 2024-07-18 10:52:23,777 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-90/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/stdout.txt 2024-07-18 10:52:23,778 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 10:52:24,981 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:24,981 DEBUG --- stdout --- 2024-07-18 10:52:24,981 DEBUG tar: Removing leading `/' from member names 2024-07-18 10:52:24,981 DEBUG --- stderr --- 2024-07-18 10:52:24,981 DEBUG ________________________________________________________________________________ [2024-07-18 10:52:24] benchmark-90 show_cmd : Display order command ________________________________________________________________________________ 2024-07-18 10:52:24,982 INFO 2024-07-18 10:52:24,982 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 10:52:24,982 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:25,301 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:25,301 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:25,301 DEBUG --- stdout --- 2024-07-18 10:52:25,301 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:25,301 DEBUG --- stderr --- 2024-07-18 10:52:25,301 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 10:52:25,302 INFO 2024-07-18 10:52:25,302 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 10:52:25,302 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:25,626 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:25,626 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:25,626 DEBUG --- stdout --- 2024-07-18 10:52:25,626 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:25,626 DEBUG --- stderr --- 2024-07-18 10:52:25,626 DEBUG 2024-07-18 10:52:25,627 INFO 2024-07-18 10:52:25,627 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-90/cmd.txt" ]; then echo "/results/orders/benchmark-90/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-90/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-90/cmd.txt file found" 2024-07-18 10:52:25,627 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 10:52:26,469 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 10:52:26,470 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:26,470 DEBUG --- stdout --- 2024-07-18 10:52:26,470 DEBUG /results/orders/benchmark-90/cmd.txt file found 2024-07-18 10:52:26,470 DEBUG --- stderr --- 2024-07-18 10:52:26,470 DEBUG 2024-07-18 10:52:26,470 INFO 2024-07-18 10:52:26,470 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 10:52:26,470 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:26,872 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:26,873 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:26,873 DEBUG --- stdout --- 2024-07-18 10:52:26,873 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:26,873 DEBUG --- stderr --- 2024-07-18 10:52:26,873 DEBUG 2024-07-18 10:52:26,876 INFO 2024-07-18 10:52:26,877 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-90/cmd.txt 2024-07-18 10:52:27,699 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 10:52:27,699 DEBUG --- stdout --- 2024-07-18 10:52:27,699 DEBUG 4 /results/orders/benchmark-90/cmd.txt 2024-07-18 10:52:27,699 DEBUG --- stderr --- 2024-07-18 10:52:27,699 DEBUG 2024-07-18 10:52:27,699 INFO - Download file (size 4k) 2024-07-18 10:52:27,700 INFO 2024-07-18 10:52:27,700 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 10:52:27,700 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:28,018 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:28,018 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:28,018 DEBUG --- stdout --- 2024-07-18 10:52:28,018 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:28,018 DEBUG --- stderr --- 2024-07-18 10:52:28,018 DEBUG source : /results/orders/benchmark-90/cmd.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/cmd.txt 2024-07-18 10:52:28,019 INFO 2024-07-18 10:52:28,019 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 10:52:28,019 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:28,340 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:28,340 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:28,340 DEBUG --- stdout --- 2024-07-18 10:52:28,341 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:28,341 DEBUG --- stderr --- 2024-07-18 10:52:28,341 DEBUG 2024-07-18 10:52:28,341 INFO 2024-07-18 10:52:28,341 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-90/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/cmd.txt 2024-07-18 10:52:28,341 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 10:52:29,141 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:29,141 DEBUG --- stdout --- 2024-07-18 10:52:29,141 DEBUG tar: Removing leading `/' from member names 2024-07-18 10:52:29,141 DEBUG --- stderr --- 2024-07-18 10:52:29,141 DEBUG 2024-07-18 10:52:29,142 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/cmd.txt is small (1 lines), printing all lines: 2024-07-18 10:52:29,142 DEBUG ----- output ----- 2024-07-18 10:52:29,142 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-90 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-07-18 10:52:29,142 INFO ________________________________________________________________________________ [2024-07-18 10:52:29] benchmark-90 show_rc : Display rc order ________________________________________________________________________________ 2024-07-18 10:52:29,142 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/rc.txt is small (1 lines), printing all lines: 2024-07-18 10:52:29,143 DEBUG ----- output ----- 2024-07-18 10:52:29,143 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-07-18 10:52:29,143 INFO ________________________________________________________________________________ [2024-07-18 10:52:29] benchmark-90 show_stdout : Display stdout order ________________________________________________________________________________ 2024-07-18 10:52:29,150 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/05_benchmark-90/stdout.txt is 39477 lines): 2024-07-18 10:52:29,150 DEBUG ----- output ----- 2024-07-18 10:52:29,150 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-07-18 10:52:29,150 INFO Gatling 3.11.5 is available! (you're using 3.9.5) 2024-07-18 10:52:29,150 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-07-18 10:52:29,150 INFO 2024-07-18 10:52:29,150 INFO ================================================================================ 2024-07-18 10:52:29,150 INFO 2024-07-18 10:50:02 5s elapsed 2024-07-18 10:52:29,150 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 10:52:29,150 INFO > Global (OK=258 KO=0 ) 2024-07-18 10:52:29,150 INFO > registration_restLoginInitiate (OK=5 KO=0 ) 2024-07-18 10:52:29,150 INFO > registrationCallback (OK=5 KO=0 ) 2024-07-18 10:52:29,150 INFO > restLoginInitiate (OK=32 KO=0 ) 2024-07-18 10:52:29,150 INFO > restLoginUsernamePasswordCallback (OK=31 KO=0 ) 2024-07-18 10:52:29,150 INFO > Skip 2FA (OK=22 KO=0 ) 2024-07-18 10:52:29,150 INFO > User Authorize PKCE stage (OK=31 KO=0 ) 2024-07-18 10:52:29,150 INFO > User AccessToken PKCE stage (OK=30 KO=0 ) 2024-07-18 10:52:29,150 INFO > RootUserinfo (OK=36 KO=0 ) 2024-07-18 10:52:29,150 INFO > tokenUserinfo (OK=6 KO=0 ) 2024-07-18 10:52:29,150 INFO > UI-Post-Login (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI-Post-Login Redirect 1 (OK=6 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI AM idFromSession (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI AM validateGoto (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI Enduser appAuthHelper2 (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI Enduser appAuthHelper1 (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI Enduser sessioncheck (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI IDM uiconfig (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI IDM version (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI IDM features (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI IDM privilege (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI IDM login (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI IDM user (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI IDM dashboard (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI IDM alpha_user (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI IDM oidcToken (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > userinfo (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI-Logout Redirect 1 (OK=2 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI AM revoke2 (OK=1 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI AM endsession (OK=1 KO=0 ) 2024-07-18 10:52:29,151 INFO > UI AM revoke1 (OK=1 KO=0 ) 2024-07-18 10:52:29,151 INFO > getIdToken (OK=4 KO=0 ) 2024-07-18 10:52:29,151 INFO > getIdToken Redirect 1 (OK=4 KO=0 ) 2024-07-18 10:52:29,151 INFO > createOrganization (OK=3 KO=0 ) 2024-07-18 10:52:29,151 INFO > queryOrganization (OK=3 KO=0 ) 2024-07-18 10:52:29,151 INFO > deleteOrganization (OK=3 KO=0 ) 2024-07-18 10:52:29,151 INFO 2024-07-18 10:52:29,151 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 10:52:29,151 INFO [- ] 0% 2024-07-18 10:52:29,151 INFO waiting: 325264 / active: 22 / done: 16 2024-07-18 10:52:29,151 INFO ================================================================================ 2024-07-18 10:52:29,151 INFO 2024-07-18 10:52:29,151 INFO 2024-07-18 10:52:29,151 INFO ================================================================================ 2024-07-18 10:52:29,151 INFO 2024-07-18 10:50:07 10s elapsed 2024-07-18 10:52:29,151 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 10:52:29,151 INFO > Global (OK=1129 KO=0 ) 2024-07-18 10:52:29,151 INFO > registration_restLoginInitiate (OK=23 KO=0 ) 2024-07-18 10:52:29,151 INFO > registrationCallback (OK=22 KO=0 ) 2024-07-18 10:52:29,151 INFO > restLoginInitiate (OK=126 KO=0 ) 2024-07-18 10:52:29,151 INFO > restLoginUsernamePasswordCallback (OK=125 KO=0 ) 2024-07-18 10:52:29,151 INFO > Skip 2FA (OK=88 KO=0 ) 2024-07-18 10:52:29,151 INFO > User Authorize PKCE stage (OK=129 KO=0 ) 2024-07-18 10:52:29,152 INFO > User AccessToken PKCE stage (OK=125 KO=0 ) 2024-07-18 10:52:29,152 INFO > RootUserinfo (OK=198 KO=0 ) 2024-07-18 10:52:29,152 INFO > tokenUserinfo (OK=26 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI-Post-Login (OK=8 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI-Post-Login Redirect 1 (OK=22 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI AM idFromSession (OK=8 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI AM validateGoto (OK=8 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI Enduser appAuthHelper2 (OK=8 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI Enduser appAuthHelper1 (OK=8 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI Enduser sessioncheck (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI IDM uiconfig (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI IDM version (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI IDM features (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI IDM privilege (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI IDM login (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI IDM user (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI IDM dashboard (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI IDM alpha_user (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI IDM oidcToken (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > userinfo (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI-Logout Redirect 1 (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI AM revoke2 (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI AM endsession (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > UI AM revoke1 (OK=6 KO=0 ) 2024-07-18 10:52:29,152 INFO > getIdToken (OK=23 KO=0 ) 2024-07-18 10:52:29,152 INFO > getIdToken Redirect 1 (OK=23 KO=0 ) 2024-07-18 10:52:29,152 INFO > createOrganization (OK=23 KO=0 ) 2024-07-18 10:52:29,152 INFO > queryOrganization (OK=23 KO=0 ) 2024-07-18 10:52:29,152 INFO > deleteOrganization (OK=23 KO=0 ) 2024-07-18 10:52:29,152 INFO 2024-07-18 10:52:29,152 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 10:52:29,152 INFO [- ] 0% 2024-07-18 10:52:29,152 INFO waiting: 325151 / active: 55 / done: 96 2024-07-18 10:52:29,152 INFO ================================================================================ 2024-07-18 10:52:29,152 INFO 2024-07-18 10:52:29,152 INFO 10:50:08.993 [ERROR] i.g.h.a.HttpRequestAction - 'tokenUserinfo' failed to execute: No attribute named 'access_token' is defined 2024-07-18 10:52:29,152 INFO 2024-07-18 10:52:29,152 INFO ================================================================================ 2024-07-18 10:52:29,152 INFO 2024-07-18 10:50:12 15s elapsed 2024-07-18 10:52:29,152 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 10:52:29,152 INFO [...] 2024-07-18 10:52:29,163 INFO > UI IDM dashboard (OK=12986 KO=0 ) 2024-07-18 10:52:29,163 INFO > UI IDM alpha_user (OK=12986 KO=0 ) 2024-07-18 10:52:29,163 INFO > UI IDM oidcToken (OK=12986 KO=0 ) 2024-07-18 10:52:29,163 INFO > userinfo (OK=12986 KO=0 ) 2024-07-18 10:52:29,163 INFO > UI-Logout Redirect 1 (OK=12986 KO=0 ) 2024-07-18 10:52:29,163 INFO > UI AM revoke2 (OK=12986 KO=0 ) 2024-07-18 10:52:29,163 INFO > UI AM endsession (OK=12986 KO=0 ) 2024-07-18 10:52:29,163 INFO > UI AM revoke1 (OK=12986 KO=0 ) 2024-07-18 10:52:29,163 INFO > getIdToken (OK=39221 KO=0 ) 2024-07-18 10:52:29,163 INFO > getIdToken Redirect 1 (OK=39221 KO=0 ) 2024-07-18 10:52:29,163 INFO > createOrganization (OK=39221 KO=0 ) 2024-07-18 10:52:29,164 INFO > queryOrganization (OK=39221 KO=0 ) 2024-07-18 10:52:29,164 INFO > deleteOrganization (OK=39221 KO=0 ) 2024-07-18 10:52:29,164 INFO > restloginProgressiveProfileCallback (OK=177 KO=0 ) 2024-07-18 10:52:29,164 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 10:52:29,164 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 889 (98.12%) 2024-07-18 10:52:29,164 INFO cess_token' is defined 2024-07-18 10:52:29,164 INFO > RootUserinfo: Failed to build request: No attribute named 'acc 10 ( 1.10%) 2024-07-18 10:52:29,164 INFO ess_token' is defined 2024-07-18 10:52:29,164 INFO > status.find.is(200), but actually found 502 4 ( 0.44%) 2024-07-18 10:52:29,164 INFO > status.find.is(302), but actually found 502 3 ( 0.33%) 2024-07-18 10:52:29,164 INFO 2024-07-18 10:52:29,164 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 10:52:29,164 INFO [#########################################################################-] 99% 2024-07-18 10:52:29,164 INFO waiting: 0 / active: 39 / done: 325263 2024-07-18 10:52:29,164 INFO ================================================================================ 2024-07-18 10:52:29,164 INFO 2024-07-18 10:52:29,164 INFO 2024-07-18 10:52:29,164 INFO ================================================================================ 2024-07-18 10:52:29,164 INFO 2024-07-18 11:50:36 3638s elapsed 2024-07-18 10:52:29,164 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 10:52:29,164 INFO > Global (OK=2754855 KO=7 ) 2024-07-18 10:52:29,164 INFO > registration_restLoginInitiate (OK=52034 KO=0 ) 2024-07-18 10:52:29,164 INFO > registrationCallback (OK=52034 KO=0 ) 2024-07-18 10:52:29,164 INFO > restLoginInitiate (OK=273266 KO=2 ) 2024-07-18 10:52:29,164 INFO > restLoginUsernamePasswordCallback (OK=273264 KO=2 ) 2024-07-18 10:52:29,164 INFO > Skip 2FA (OK=195061 KO=0 ) 2024-07-18 10:52:29,164 INFO > User Authorize PKCE stage (OK=285359 KO=3 ) 2024-07-18 10:52:29,164 INFO > User AccessToken PKCE stage (OK=285359 KO=0 ) 2024-07-18 10:52:29,164 INFO > RootUserinfo (OK=779190 KO=0 ) 2024-07-18 10:52:29,164 INFO > tokenUserinfo (OK=64328 KO=0 ) 2024-07-18 10:52:29,164 INFO > UI-Post-Login (OK=12986 KO=0 ) 2024-07-18 10:52:29,164 INFO > UI-Post-Login Redirect 1 (OK=38958 KO=0 ) 2024-07-18 10:52:29,164 INFO > UI AM idFromSession (OK=12986 KO=0 ) 2024-07-18 10:52:29,164 INFO > UI AM validateGoto (OK=12986 KO=0 ) 2024-07-18 10:52:29,164 INFO > UI Enduser appAuthHelper2 (OK=12986 KO=0 ) 2024-07-18 10:52:29,164 INFO > UI Enduser appAuthHelper1 (OK=12986 KO=0 ) 2024-07-18 10:52:29,164 INFO > UI Enduser sessioncheck (OK=12986 KO=0 ) 2024-07-18 10:52:29,164 INFO > UI IDM uiconfig (OK=12986 KO=0 ) 2024-07-18 10:52:29,164 INFO > UI IDM version (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > UI IDM features (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > UI IDM privilege (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > UI IDM login (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > UI IDM user (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > UI IDM dashboard (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > UI IDM alpha_user (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > UI IDM oidcToken (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > userinfo (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > UI-Logout Redirect 1 (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > UI AM revoke2 (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > UI AM endsession (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > UI AM revoke1 (OK=12986 KO=0 ) 2024-07-18 10:52:29,165 INFO > getIdToken (OK=39221 KO=0 ) 2024-07-18 10:52:29,165 INFO > getIdToken Redirect 1 (OK=39221 KO=0 ) 2024-07-18 10:52:29,165 INFO > createOrganization (OK=39221 KO=0 ) 2024-07-18 10:52:29,165 INFO > queryOrganization (OK=39221 KO=0 ) 2024-07-18 10:52:29,166 INFO > deleteOrganization (OK=39221 KO=0 ) 2024-07-18 10:52:29,166 INFO > restloginProgressiveProfileCallback (OK=177 KO=0 ) 2024-07-18 10:52:29,166 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 10:52:29,166 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 889 (98.12%) 2024-07-18 10:52:29,166 INFO cess_token' is defined 2024-07-18 10:52:29,166 INFO > RootUserinfo: Failed to build request: No attribute named 'acc 10 ( 1.10%) 2024-07-18 10:52:29,166 INFO ess_token' is defined 2024-07-18 10:52:29,166 INFO > status.find.is(200), but actually found 502 4 ( 0.44%) 2024-07-18 10:52:29,166 INFO > status.find.is(302), but actually found 502 3 ( 0.33%) 2024-07-18 10:52:29,166 INFO 2024-07-18 10:52:29,166 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 10:52:29,166 INFO [##########################################################################]100% 2024-07-18 10:52:29,166 INFO waiting: 0 / active: 0 / done: 325302 2024-07-18 10:52:29,166 INFO ================================================================================ 2024-07-18 10:52:29,167 INFO 2024-07-18 10:52:29,167 INFO Simulation com.forgerock.gatling.Loadtest completed in 3638 seconds 2024-07-18 10:52:29,167 INFO 2024-07-18 10:52:29,167 INFO ---------------------- 2024-07-18 10:52:29,167 INFO [18/Jul/2024 11:50:36] Create tar file benchmark-90.tar.gz 2024-07-18 10:52:29,167 INFO ---------------------- 2024-07-18 10:52:29,167 INFO RESULTS_FOLDER=/results/gatling/ 2024-07-18 10:52:29,167 INFO RESULT_FOLDER=benchmark-90 2024-07-18 10:52:29,167 INFO SIMULATION_PATH=benchmark-90/loadtest-20240718094957452 2024-07-18 10:52:29,167 INFO PWD=/results/gatling/benchmark-90/loadtest-20240718094957452 2024-07-18 10:52:29,167 INFO [cmd] tar -czvf /results/gatling//benchmark-90.tar.gz --exclude=simulation.log * 2024-07-18 10:52:29,167 INFO /results/gatling 2024-07-18 10:52:29,167 INFO 2024-07-18 10:52:29,167 INFO ---------------------- 2024-07-18 10:52:29,167 INFO [18/Jul/2024 11:50:36] Update /results/gatling//latest 2024-07-18 10:52:29,167 INFO ---------------------- 2024-07-18 10:52:29,167 INFO 2024-07-18 10:52:29,167 INFO ---------------------- 2024-07-18 10:52:29,167 INFO [18/Jul/2024 11:50:36] Update /results/gatling//latest.tar.gz 2024-07-18 10:52:29,167 INFO ---------------------- 2024-07-18 10:52:29,167 INFO ________________________________________________________________________________ [2024-07-18 10:52:29] benchmark-90 show_stderr : Display stderr order ________________________________________________________________________________ 2024-07-18 10:52:29,168 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/05_benchmark-90/stderr.txt is small (0 lines), printing all lines: 2024-07-18 10:52:29,168 DEBUG ----- output ----- 2024-07-18 10:52:29,168 DEBUG 2024-07-18 10:52:29,168 INFO ________________________________________________________________________________ [2024-07-18 10:52:29] benchmark-90 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 10:52:29] benchmark-90 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-07-18 10:52:29] benchmark-90 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped