--Task-- name: benchmark-130 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-110'] preceding_task: benchmark-110 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self130-', 'testCase': 'idc.benchmark.CH2023', 'testMode': 'open', 'throughput': 130} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-07-18 11:55:08] benchmark-130 pre : N/A ________________________________________________________________________________ 2024-07-18 11:55:08 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-07-18 11:55:08 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-130/stdout.txt Pod log : /results/orders/benchmark-130 2024-07-18 11:55:08,944 INFO 2024-07-18 11:55:08,944 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 11:55:08,944 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:09,253 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:09,253 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:09,253 DEBUG --- stdout --- 2024-07-18 11:55:09,253 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:09,253 DEBUG --- stderr --- 2024-07-18 11:55:09,253 DEBUG ________________________________________________________________________________ [2024-07-18 11:55:09] benchmark-130 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-07-18 11:55:09,254 INFO 2024-07-18 11:55:09,254 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 11:55:09,255 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:09,570 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:09,570 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:09,570 DEBUG --- stdout --- 2024-07-18 11:55:09,570 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:09,570 DEBUG --- stderr --- 2024-07-18 11:55:09,570 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-130 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-07-18 11:55:09] benchmark-130 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-07-18 11:55:09,571 INFO 2024-07-18 11:55:09,571 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 11:55:09,571 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:09,888 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:09,888 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:09,889 DEBUG --- stdout --- 2024-07-18 11:55:09,889 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:09,889 DEBUG --- stderr --- 2024-07-18 11:55:09,889 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/order.json { "name": "benchmark-130", "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-130 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/benchmark-130", "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=self130- -Drealm=alpha -DtestCase=idc.benchmark.CH2023 -DtestMode=open -Dthroughput=130 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-07-18 11:55:09] benchmark-130 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 11:55:09,891 INFO 2024-07-18 11:55:09,891 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-07-18 11:55:09,891 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-18 11:55:10,148 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 11:55:10,148 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:10,148 DEBUG --- stdout --- 2024-07-18 11:55:10,148 DEBUG WAITING 2024-07-18 11:55:10,148 DEBUG --- stderr --- 2024-07-18 11:55:10,148 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-07-18 11:55:10] benchmark-130 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-07-18 11:55:10,149 INFO 2024-07-18 11:55:10,149 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 11:55:10,149 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:10,465 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:10,465 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:10,465 DEBUG --- stdout --- 2024-07-18 11:55:10,465 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:10,465 DEBUG --- stderr --- 2024-07-18 11:55:10,466 DEBUG 2024-07-18 11:55:10,466 INFO 2024-07-18 11:55:10,466 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/07_benchmark-130/order.json overseer-0-7796fc5d9b-ngsxg:/results/orders/order.json 2024-07-18 11:55:10,466 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 11:55:11,837 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:11,838 DEBUG --- stdout --- 2024-07-18 11:55:11,838 DEBUG 2024-07-18 11:55:11,838 DEBUG --- stderr --- 2024-07-18 11:55:11,838 DEBUG Order has been successfully forwarded to overseer 2024-07-18 11:55:11 - INFO: Check if Gatling simulation started successfully 2024-07-18 11:55:11,838 INFO 2024-07-18 11:55:11,838 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 11:55:11,838 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:12,152 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:12,152 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:12,152 DEBUG --- stdout --- 2024-07-18 11:55:12,153 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:12,153 DEBUG --- stderr --- 2024-07-18 11:55:12,153 DEBUG 2024-07-18 11:55:12,153 INFO 2024-07-18 11:55:12,153 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-130/stdout.txt | grep "started..." 2024-07-18 11:55:12,153 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-18 11:55:12,902 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-07-18 11:55:18,654 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-07-18 11:55:18,654 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:18,654 DEBUG --- stdout --- 2024-07-18 11:55:18,654 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 11:55:18,654 DEBUG --- stderr --- 2024-07-18 11:55:18,654 DEBUG ________________________________________________________________________________ [2024-07-18 11:55:18] benchmark-130 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 12:57:28] benchmark-130 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-07-18 12:57:28,841 INFO 2024-07-18 12:57:28,842 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:28,842 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:29,181 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:29,181 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:29,181 DEBUG --- stdout --- 2024-07-18 12:57:29,181 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:29,181 DEBUG --- stderr --- 2024-07-18 12:57:29,182 DEBUG 2024-07-18 12:57:29,182 INFO 2024-07-18 12:57:29,182 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 12:57:29,182 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 12:57:29,999 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:29,999 DEBUG --- stdout --- 2024-07-18 12:57:29,999 DEBUG 2024-07-18 12:57:29,999 DEBUG --- stderr --- 2024-07-18 12:57:29,999 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-07-18 12:57:29] benchmark-130 download_logs : Download individual order files ________________________________________________________________________________ 2024-07-18 12:57:30 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/order.json 2024-07-18 12:57:30,000 INFO 2024-07-18 12:57:30,001 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:30,001 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:30,315 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:30,315 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:30,315 DEBUG --- stdout --- 2024-07-18 12:57:30,315 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:30,315 DEBUG --- stderr --- 2024-07-18 12:57:30,315 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 12:57:30,316 INFO 2024-07-18 12:57:30,316 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:30,316 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:30,631 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:30,631 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:30,631 DEBUG --- stdout --- 2024-07-18 12:57:30,632 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:30,632 DEBUG --- stderr --- 2024-07-18 12:57:30,632 DEBUG 2024-07-18 12:57:30,632 INFO 2024-07-18 12:57:30,632 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-130/rc.txt" ]; then echo "/results/orders/benchmark-130/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-130/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-130/rc.txt file found" 2024-07-18 12:57:30,632 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 12:57:31,454 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 12:57:31,454 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:31,454 DEBUG --- stdout --- 2024-07-18 12:57:31,455 DEBUG /results/orders/benchmark-130/rc.txt file found 2024-07-18 12:57:31,455 DEBUG --- stderr --- 2024-07-18 12:57:31,455 DEBUG 2024-07-18 12:57:31,455 INFO 2024-07-18 12:57:31,455 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 12:57:31,455 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:31,757 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:31,757 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:31,757 DEBUG --- stdout --- 2024-07-18 12:57:31,757 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:31,757 DEBUG --- stderr --- 2024-07-18 12:57:31,757 DEBUG 2024-07-18 12:57:31,758 INFO 2024-07-18 12:57:31,758 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-130/rc.txt 2024-07-18 12:57:32,565 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 12:57:32,565 DEBUG --- stdout --- 2024-07-18 12:57:32,566 DEBUG 4 /results/orders/benchmark-130/rc.txt 2024-07-18 12:57:32,566 DEBUG --- stderr --- 2024-07-18 12:57:32,566 DEBUG 2024-07-18 12:57:32,566 INFO - Download file (size 4k) 2024-07-18 12:57:32,566 INFO 2024-07-18 12:57:32,566 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:32,566 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:32,925 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:32,926 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:32,926 DEBUG --- stdout --- 2024-07-18 12:57:32,926 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:32,926 DEBUG --- stderr --- 2024-07-18 12:57:32,926 DEBUG source : /results/orders/benchmark-130/rc.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/rc.txt 2024-07-18 12:57:32,926 INFO 2024-07-18 12:57:32,926 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:32,926 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:33,257 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:33,257 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:33,257 DEBUG --- stdout --- 2024-07-18 12:57:33,257 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:33,257 DEBUG --- stderr --- 2024-07-18 12:57:33,257 DEBUG 2024-07-18 12:57:33,258 INFO 2024-07-18 12:57:33,258 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-130/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/rc.txt 2024-07-18 12:57:33,258 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 12:57:34,005 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:34,005 DEBUG --- stdout --- 2024-07-18 12:57:34,005 DEBUG tar: Removing leading `/' from member names 2024-07-18 12:57:34,005 DEBUG --- stderr --- 2024-07-18 12:57:34,005 DEBUG 2024-07-18 12:57:34,006 INFO 2024-07-18 12:57:34,006 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:34,006 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:34,310 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:34,310 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:34,310 DEBUG --- stdout --- 2024-07-18 12:57:34,310 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:34,310 DEBUG --- stderr --- 2024-07-18 12:57:34,310 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 12:57:34,311 INFO 2024-07-18 12:57:34,311 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:34,311 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:34,612 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:34,613 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:34,613 DEBUG --- stdout --- 2024-07-18 12:57:34,613 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:34,613 DEBUG --- stderr --- 2024-07-18 12:57:34,613 DEBUG 2024-07-18 12:57:34,613 INFO 2024-07-18 12:57:34,613 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-130/stderr.txt" ]; then echo "/results/orders/benchmark-130/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-130/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-130/stderr.txt file found" 2024-07-18 12:57:34,614 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 12:57:35,415 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 12:57:35,415 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:35,415 DEBUG --- stdout --- 2024-07-18 12:57:35,416 DEBUG /results/orders/benchmark-130/stderr.txt file found 2024-07-18 12:57:35,416 DEBUG --- stderr --- 2024-07-18 12:57:35,416 DEBUG 2024-07-18 12:57:35,416 INFO 2024-07-18 12:57:35,416 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:35,416 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:35,721 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:35,721 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:35,721 DEBUG --- stdout --- 2024-07-18 12:57:35,721 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:35,722 DEBUG --- stderr --- 2024-07-18 12:57:35,722 DEBUG 2024-07-18 12:57:35,722 INFO 2024-07-18 12:57:35,722 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-130/stderr.txt 2024-07-18 12:57:36,552 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 12:57:36,552 DEBUG --- stdout --- 2024-07-18 12:57:36,552 DEBUG 0 /results/orders/benchmark-130/stderr.txt 2024-07-18 12:57:36,553 DEBUG --- stderr --- 2024-07-18 12:57:36,553 DEBUG 2024-07-18 12:57:36,553 INFO - Download file (size 0k) 2024-07-18 12:57:36,553 INFO 2024-07-18 12:57:36,553 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 12:57:36,553 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:36,873 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:36,873 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:36,873 DEBUG --- stdout --- 2024-07-18 12:57:36,873 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:36,873 DEBUG --- stderr --- 2024-07-18 12:57:36,873 DEBUG source : /results/orders/benchmark-130/stderr.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/stderr.txt 2024-07-18 12:57:36,873 INFO 2024-07-18 12:57:36,873 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 12:57:36,874 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:37,193 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:37,193 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:37,193 DEBUG --- stdout --- 2024-07-18 12:57:37,193 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:37,193 DEBUG --- stderr --- 2024-07-18 12:57:37,193 DEBUG 2024-07-18 12:57:37,193 INFO 2024-07-18 12:57:37,194 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-130/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/stderr.txt 2024-07-18 12:57:37,194 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 12:57:37,986 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:37,987 DEBUG --- stdout --- 2024-07-18 12:57:37,987 DEBUG tar: Removing leading `/' from member names 2024-07-18 12:57:37,987 DEBUG --- stderr --- 2024-07-18 12:57:37,987 DEBUG 2024-07-18 12:57:37,987 INFO 2024-07-18 12:57:37,988 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:37,988 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:38,307 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:38,307 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:38,307 DEBUG --- stdout --- 2024-07-18 12:57:38,307 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:38,307 DEBUG --- stderr --- 2024-07-18 12:57:38,307 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 12:57:38,308 INFO 2024-07-18 12:57:38,308 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:38,308 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:38,626 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:38,627 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:38,627 DEBUG --- stdout --- 2024-07-18 12:57:38,627 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:38,627 DEBUG --- stderr --- 2024-07-18 12:57:38,627 DEBUG 2024-07-18 12:57:38,628 INFO 2024-07-18 12:57:38,628 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-130/stdout.txt" ]; then echo "/results/orders/benchmark-130/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-130/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-130/stdout.txt file found" 2024-07-18 12:57:38,628 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 12:57:39,456 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 12:57:39,456 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:39,456 DEBUG --- stdout --- 2024-07-18 12:57:39,456 DEBUG /results/orders/benchmark-130/stdout.txt file found 2024-07-18 12:57:39,456 DEBUG --- stderr --- 2024-07-18 12:57:39,456 DEBUG 2024-07-18 12:57:39,457 INFO 2024-07-18 12:57:39,457 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:39,457 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:39,775 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:39,776 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:39,776 DEBUG --- stdout --- 2024-07-18 12:57:39,776 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:39,776 DEBUG --- stderr --- 2024-07-18 12:57:39,776 DEBUG 2024-07-18 12:57:39,776 INFO 2024-07-18 12:57:39,776 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-130/stdout.txt 2024-07-18 12:57:40,595 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 12:57:40,596 DEBUG --- stdout --- 2024-07-18 12:57:40,596 DEBUG 3456 /results/orders/benchmark-130/stdout.txt 2024-07-18 12:57:40,596 DEBUG --- stderr --- 2024-07-18 12:57:40,596 DEBUG 2024-07-18 12:57:40,596 INFO - Download file (size 3456k) 2024-07-18 12:57:40,597 INFO 2024-07-18 12:57:40,597 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:40,597 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:40,903 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:40,903 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:40,903 DEBUG --- stdout --- 2024-07-18 12:57:40,903 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:40,903 DEBUG --- stderr --- 2024-07-18 12:57:40,903 DEBUG source : /results/orders/benchmark-130/stdout.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/stdout.txt 2024-07-18 12:57:40,904 INFO 2024-07-18 12:57:40,904 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 12:57:40,904 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:41,210 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:41,210 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:41,210 DEBUG --- stdout --- 2024-07-18 12:57:41,210 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:41,210 DEBUG --- stderr --- 2024-07-18 12:57:41,210 DEBUG 2024-07-18 12:57:41,211 INFO 2024-07-18 12:57:41,211 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-130/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/stdout.txt 2024-07-18 12:57:41,211 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 12:57:42,454 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:42,454 DEBUG --- stdout --- 2024-07-18 12:57:42,454 DEBUG tar: Removing leading `/' from member names 2024-07-18 12:57:42,454 DEBUG --- stderr --- 2024-07-18 12:57:42,454 DEBUG ________________________________________________________________________________ [2024-07-18 12:57:42] benchmark-130 show_cmd : Display order command ________________________________________________________________________________ 2024-07-18 12:57:42,455 INFO 2024-07-18 12:57:42,455 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 12:57:42,456 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:42,761 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:42,761 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:42,761 DEBUG --- stdout --- 2024-07-18 12:57:42,761 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:42,761 DEBUG --- stderr --- 2024-07-18 12:57:42,761 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 12:57:42,762 INFO 2024-07-18 12:57:42,762 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:42,762 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:43,070 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:43,070 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:43,070 DEBUG --- stdout --- 2024-07-18 12:57:43,070 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:43,070 DEBUG --- stderr --- 2024-07-18 12:57:43,070 DEBUG 2024-07-18 12:57:43,071 INFO 2024-07-18 12:57:43,071 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-130/cmd.txt" ]; then echo "/results/orders/benchmark-130/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-130/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-130/cmd.txt file found" 2024-07-18 12:57:43,071 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 12:57:43,875 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 12:57:43,875 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:43,875 DEBUG --- stdout --- 2024-07-18 12:57:43,875 DEBUG /results/orders/benchmark-130/cmd.txt file found 2024-07-18 12:57:43,875 DEBUG --- stderr --- 2024-07-18 12:57:43,875 DEBUG 2024-07-18 12:57:43,875 INFO 2024-07-18 12:57:43,875 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:43,875 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:44,182 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:44,183 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:44,183 DEBUG --- stdout --- 2024-07-18 12:57:44,183 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:44,183 DEBUG --- stderr --- 2024-07-18 12:57:44,183 DEBUG 2024-07-18 12:57:44,183 INFO 2024-07-18 12:57:44,183 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-130/cmd.txt 2024-07-18 12:57:44,984 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 12:57:44,985 DEBUG --- stdout --- 2024-07-18 12:57:44,985 DEBUG 4 /results/orders/benchmark-130/cmd.txt 2024-07-18 12:57:44,985 DEBUG --- stderr --- 2024-07-18 12:57:44,985 DEBUG 2024-07-18 12:57:44,985 INFO - Download file (size 4k) 2024-07-18 12:57:44,985 INFO 2024-07-18 12:57:44,985 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:44,985 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:45,290 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:45,290 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:45,290 DEBUG --- stdout --- 2024-07-18 12:57:45,290 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:45,290 DEBUG --- stderr --- 2024-07-18 12:57:45,290 DEBUG source : /results/orders/benchmark-130/cmd.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/cmd.txt 2024-07-18 12:57:45,291 INFO 2024-07-18 12:57:45,291 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:45,291 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 12:57:45,606 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 12:57:45,606 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:45,606 DEBUG --- stdout --- 2024-07-18 12:57:45,606 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 12:57:45,606 DEBUG --- stderr --- 2024-07-18 12:57:45,606 DEBUG 2024-07-18 12:57:45,607 INFO 2024-07-18 12:57:45,607 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-130/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/cmd.txt 2024-07-18 12:57:45,607 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 12:57:46,350 INFO [loop_until]: OK (rc = 0) 2024-07-18 12:57:46,350 DEBUG --- stdout --- 2024-07-18 12:57:46,350 DEBUG tar: Removing leading `/' from member names 2024-07-18 12:57:46,350 DEBUG --- stderr --- 2024-07-18 12:57:46,350 DEBUG 2024-07-18 12:57:46,351 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/cmd.txt is small (1 lines), printing all lines: 2024-07-18 12:57:46,351 DEBUG ----- output ----- 2024-07-18 12:57:46,351 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-130 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-07-18 12:57:46,351 INFO ________________________________________________________________________________ [2024-07-18 12:57:46] benchmark-130 show_rc : Display rc order ________________________________________________________________________________ 2024-07-18 12:57:46,351 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/rc.txt is small (1 lines), printing all lines: 2024-07-18 12:57:46,352 DEBUG ----- output ----- 2024-07-18 12:57:46,352 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-07-18 12:57:46,352 INFO ________________________________________________________________________________ [2024-07-18 12:57:46] benchmark-130 show_stdout : Display stdout order ________________________________________________________________________________ 2024-07-18 12:57:46,359 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/07_benchmark-130/stdout.txt is 44336 lines): 2024-07-18 12:57:46,359 DEBUG ----- output ----- 2024-07-18 12:57:46,359 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-07-18 12:57:46,359 INFO Gatling 3.11.5 is available! (you're using 3.9.5) 2024-07-18 12:57:46,359 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-07-18 12:57:46,359 INFO 2024-07-18 12:57:46,359 INFO ================================================================================ 2024-07-18 12:57:46,359 INFO 2024-07-18 12:55:21 5s elapsed 2024-07-18 12:57:46,360 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 12:57:46,360 INFO > Global (OK=353 KO=0 ) 2024-07-18 12:57:46,360 INFO > registration_restLoginInitiate (OK=11 KO=0 ) 2024-07-18 12:57:46,360 INFO > registrationCallback (OK=11 KO=0 ) 2024-07-18 12:57:46,360 INFO > restLoginInitiate (OK=43 KO=0 ) 2024-07-18 12:57:46,360 INFO > restLoginUsernamePasswordCallback (OK=41 KO=0 ) 2024-07-18 12:57:46,360 INFO > Skip 2FA (OK=25 KO=0 ) 2024-07-18 12:57:46,360 INFO > User Authorize PKCE stage (OK=42 KO=0 ) 2024-07-18 12:57:46,360 INFO > User AccessToken PKCE stage (OK=41 KO=0 ) 2024-07-18 12:57:46,360 INFO > RootUserinfo (OK=45 KO=0 ) 2024-07-18 12:57:46,360 INFO > getIdToken (OK=4 KO=0 ) 2024-07-18 12:57:46,360 INFO > getIdToken Redirect 1 (OK=4 KO=0 ) 2024-07-18 12:57:46,360 INFO > createOrganization (OK=4 KO=0 ) 2024-07-18 12:57:46,360 INFO > queryOrganization (OK=4 KO=0 ) 2024-07-18 12:57:46,360 INFO > deleteOrganization (OK=4 KO=0 ) 2024-07-18 12:57:46,360 INFO > tokenUserinfo (OK=11 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI-Post-Login (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI-Post-Login Redirect 1 (OK=9 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI AM validateGoto (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI AM idFromSession (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI Enduser appAuthHelper1 (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI Enduser appAuthHelper2 (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI Enduser sessioncheck (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI IDM uiconfig (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI IDM version (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI IDM login (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI IDM features (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI IDM privilege (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI IDM user (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI IDM alpha_user (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI IDM dashboard (OK=3 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI IDM oidcToken (OK=2 KO=0 ) 2024-07-18 12:57:46,360 INFO > userinfo (OK=2 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI-Logout Redirect 1 (OK=2 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI AM revoke2 (OK=2 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI AM endsession (OK=2 KO=0 ) 2024-07-18 12:57:46,360 INFO > UI AM revoke1 (OK=2 KO=0 ) 2024-07-18 12:57:46,360 INFO 2024-07-18 12:57:46,360 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 12:57:46,360 INFO [- ] 0% 2024-07-18 12:57:46,361 INFO waiting: 470564 / active: 26 / done: 29 2024-07-18 12:57:46,361 INFO ================================================================================ 2024-07-18 12:57:46,361 INFO 2024-07-18 12:57:46,361 INFO 12:55:22.720 [ERROR] i.g.h.a.HttpRequestAction - 'tokenUserinfo' failed to execute: No attribute named 'access_token' is defined 2024-07-18 12:57:46,361 INFO 2024-07-18 12:57:46,361 INFO ================================================================================ 2024-07-18 12:57:46,361 INFO 2024-07-18 12:55:26 10s elapsed 2024-07-18 12:57:46,361 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 12:57:46,361 INFO > Global (OK=1562 KO=0 ) 2024-07-18 12:57:46,361 INFO > registration_restLoginInitiate (OK=34 KO=0 ) 2024-07-18 12:57:46,361 INFO > registrationCallback (OK=32 KO=0 ) 2024-07-18 12:57:46,361 INFO > restLoginInitiate (OK=181 KO=0 ) 2024-07-18 12:57:46,361 INFO > restLoginUsernamePasswordCallback (OK=177 KO=0 ) 2024-07-18 12:57:46,361 INFO > Skip 2FA (OK=112 KO=0 ) 2024-07-18 12:57:46,361 INFO > User Authorize PKCE stage (OK=183 KO=0 ) 2024-07-18 12:57:46,361 INFO > User AccessToken PKCE stage (OK=183 KO=0 ) 2024-07-18 12:57:46,361 INFO > RootUserinfo (OK=281 KO=0 ) 2024-07-18 12:57:46,361 INFO > getIdToken (OK=21 KO=0 ) 2024-07-18 12:57:46,361 INFO > getIdToken Redirect 1 (OK=20 KO=0 ) 2024-07-18 12:57:46,361 INFO > createOrganization (OK=20 KO=0 ) 2024-07-18 12:57:46,361 INFO > queryOrganization (OK=19 KO=0 ) 2024-07-18 12:57:46,361 INFO > deleteOrganization (OK=19 KO=0 ) 2024-07-18 12:57:46,361 INFO > tokenUserinfo (OK=50 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI-Post-Login (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI-Post-Login Redirect 1 (OK=30 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI AM validateGoto (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI AM idFromSession (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI Enduser appAuthHelper1 (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI Enduser appAuthHelper2 (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI Enduser sessioncheck (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI IDM uiconfig (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI IDM version (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI IDM login (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI IDM features (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI IDM privilege (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI IDM user (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI IDM alpha_user (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI IDM dashboard (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > UI IDM oidcToken (OK=10 KO=0 ) 2024-07-18 12:57:46,361 INFO > userinfo (OK=10 KO=0 ) 2024-07-18 12:57:46,362 INFO > UI-Logout Redirect 1 (OK=10 KO=0 ) 2024-07-18 12:57:46,362 INFO > UI AM revoke2 (OK=10 KO=0 ) 2024-07-18 12:57:46,362 INFO > UI AM endsession (OK=10 KO=0 ) 2024-07-18 12:57:46,362 INFO > UI AM revoke1 (OK=10 KO=0 ) 2024-07-18 12:57:46,362 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 12:57:46,362 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 1 (100.0%) 2024-07-18 12:57:46,362 INFO cess_token' is defined 2024-07-18 12:57:46,362 INFO 2024-07-18 12:57:46,362 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 12:57:46,362 INFO [- ] 0% 2024-07-18 12:57:46,362 INFO waiting: 470402 / active: 84 / done: 133 2024-07-18 12:57:46,362 INFO ================================================================================ 2024-07-18 12:57:46,362 INFO 2024-07-18 12:57:46,362 INFO 12:55:26.676 [ERROR] i.g.h.a.HttpRequestAction - 'tokenUserinfo' failed to execute: No attribute named 'access_token' is defined 2024-07-18 12:57:46,362 INFO [...] 2024-07-18 12:57:46,374 INFO waiting: 0 / active: 56 / done: 470563 2024-07-18 12:57:46,375 INFO ================================================================================ 2024-07-18 12:57:46,375 INFO 2024-07-18 12:57:46,375 INFO 2024-07-18 12:57:46,375 INFO ================================================================================ 2024-07-18 12:57:46,375 INFO 2024-07-18 13:55:53 3637s elapsed 2024-07-18 12:57:46,375 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 12:57:46,375 INFO > Global (OK=3978770 KO=9 ) 2024-07-18 12:57:46,375 INFO > registration_restLoginInitiate (OK=75212 KO=0 ) 2024-07-18 12:57:46,375 INFO > registrationCallback (OK=75212 KO=0 ) 2024-07-18 12:57:46,375 INFO > restLoginInitiate (OK=395405 KO=2 ) 2024-07-18 12:57:46,375 INFO > restLoginUsernamePasswordCallback (OK=395403 KO=2 ) 2024-07-18 12:57:46,375 INFO > Skip 2FA (OK=282453 KO=2 ) 2024-07-18 12:57:46,375 INFO > User Authorize PKCE stage (OK=409702 KO=1 ) 2024-07-18 12:57:46,375 INFO > User AccessToken PKCE stage (OK=409702 KO=0 ) 2024-07-18 12:57:46,375 INFO > RootUserinfo (OK=1129420 KO=0 ) 2024-07-18 12:57:46,375 INFO > getIdToken (OK=56568 KO=0 ) 2024-07-18 12:57:46,375 INFO > getIdToken Redirect 1 (OK=56568 KO=0 ) 2024-07-18 12:57:46,375 INFO > createOrganization (OK=56568 KO=0 ) 2024-07-18 12:57:46,375 INFO > queryOrganization (OK=56568 KO=0 ) 2024-07-18 12:57:46,375 INFO > deleteOrganization (OK=56568 KO=0 ) 2024-07-18 12:57:46,375 INFO > tokenUserinfo (OK=89604 KO=0 ) 2024-07-18 12:57:46,375 INFO > UI-Post-Login (OK=18824 KO=0 ) 2024-07-18 12:57:46,375 INFO > UI-Post-Login Redirect 1 (OK=56472 KO=0 ) 2024-07-18 12:57:46,375 INFO > UI AM validateGoto (OK=18824 KO=0 ) 2024-07-18 12:57:46,375 INFO > UI AM idFromSession (OK=18823 KO=1 ) 2024-07-18 12:57:46,375 INFO > UI Enduser appAuthHelper1 (OK=18824 KO=0 ) 2024-07-18 12:57:46,375 INFO > UI Enduser appAuthHelper2 (OK=18824 KO=0 ) 2024-07-18 12:57:46,375 INFO > UI Enduser sessioncheck (OK=18824 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI IDM uiconfig (OK=18824 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI IDM version (OK=18824 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI IDM login (OK=18823 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI IDM features (OK=18823 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI IDM privilege (OK=18823 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI IDM user (OK=18823 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI IDM alpha_user (OK=18823 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI IDM dashboard (OK=18823 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI IDM oidcToken (OK=18823 KO=1 ) 2024-07-18 12:57:46,376 INFO > userinfo (OK=18823 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI-Logout Redirect 1 (OK=18824 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI AM revoke2 (OK=18823 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI AM endsession (OK=18823 KO=0 ) 2024-07-18 12:57:46,376 INFO > UI AM revoke1 (OK=18823 KO=0 ) 2024-07-18 12:57:46,376 INFO > restloginProgressiveProfileCallback (OK=877 KO=0 ) 2024-07-18 12:57:46,376 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 12:57:46,376 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 4522 (99.36%) 2024-07-18 12:57:46,376 INFO cess_token' is defined 2024-07-18 12:57:46,376 INFO > RootUserinfo: Failed to build request: No attribute named 'acc 10 ( 0.22%) 2024-07-18 12:57:46,376 INFO ess_token' is defined 2024-07-18 12:57:46,376 INFO > status.find.is(200), but actually found 502 5 ( 0.11%) 2024-07-18 12:57:46,376 INFO > status.find.is(302), but actually found 502 1 ( 0.02%) 2024-07-18 12:57:46,376 INFO > status.find.is(200), but actually found 403 1 ( 0.02%) 2024-07-18 12:57:46,376 INFO > status.find.in([200, 209], 304), found 401 1 ( 0.02%) 2024-07-18 12:57:46,376 INFO > UI IDM features: Failed to build request: No attribute named 1 ( 0.02%) 2024-07-18 12:57:46,376 INFO 'access_token_2' is defined 2024-07-18 12:57:46,376 INFO > UI IDM login: Failed to build request: No attribute named 'ac 1 ( 0.02%) 2024-07-18 12:57:46,376 INFO cess_token_2' is defined 2024-07-18 12:57:46,376 INFO > UI IDM privilege: Failed to build request: No attribute named 1 ( 0.02%) 2024-07-18 12:57:46,376 INFO 'access_token_2' is defined 2024-07-18 12:57:46,376 INFO > UI IDM alpha_user: Failed to build request: No attribute name 1 ( 0.02%) 2024-07-18 12:57:46,376 INFO d 'access_token_2' is defined 2024-07-18 12:57:46,376 INFO > UI IDM user: Failed to build request: No attribute named 'use 1 ( 0.02%) 2024-07-18 12:57:46,376 INFO r_uuid' is defined 2024-07-18 12:57:46,376 INFO > UI IDM dashboard: Failed to build request: No attribute named 1 ( 0.02%) 2024-07-18 12:57:46,376 INFO 'access_token_2' is defined 2024-07-18 12:57:46,376 INFO > headerRegex((location,#id_token=(.*))).find.exists, found noth 1 ( 0.02%) 2024-07-18 12:57:46,376 INFO ing 2024-07-18 12:57:46,376 INFO > userinfo: Failed to build request: No attribute named 'access_ 1 ( 0.02%) 2024-07-18 12:57:46,377 INFO token' is defined 2024-07-18 12:57:46,377 INFO > UI AM revoke1: Failed to build request: No attribute named 'a 1 ( 0.02%) 2024-07-18 12:57:46,377 INFO ccess_token' is defined 2024-07-18 12:57:46,377 INFO > UI AM revoke2: Failed to build request: No attribute named 'a 1 ( 0.02%) 2024-07-18 12:57:46,377 INFO ccess_token_2' is defined 2024-07-18 12:57:46,377 INFO > UI AM endsession: Failed to build request: No attribute named 1 ( 0.02%) 2024-07-18 12:57:46,377 INFO 'id_token_2' is defined 2024-07-18 12:57:46,377 INFO 2024-07-18 12:57:46,377 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 12:57:46,377 INFO [##########################################################################]100% 2024-07-18 12:57:46,377 INFO waiting: 0 / active: 0 / done: 470619 2024-07-18 12:57:46,377 INFO ================================================================================ 2024-07-18 12:57:46,377 INFO 2024-07-18 12:57:46,377 INFO Simulation com.forgerock.gatling.Loadtest completed in 3637 seconds 2024-07-18 12:57:46,377 INFO 2024-07-18 12:57:46,377 INFO ---------------------- 2024-07-18 12:57:46,377 INFO [18/Jul/2024 13:55:54] Create tar file benchmark-130.tar.gz 2024-07-18 12:57:46,377 INFO ---------------------- 2024-07-18 12:57:46,377 INFO RESULTS_FOLDER=/results/gatling/ 2024-07-18 12:57:46,377 INFO RESULT_FOLDER=benchmark-130 2024-07-18 12:57:46,377 INFO SIMULATION_PATH=benchmark-130/loadtest-20240718115515447 2024-07-18 12:57:46,377 INFO PWD=/results/gatling/benchmark-130/loadtest-20240718115515447 2024-07-18 12:57:46,377 INFO [cmd] tar -czvf /results/gatling//benchmark-130.tar.gz --exclude=simulation.log * 2024-07-18 12:57:46,377 INFO /results/gatling 2024-07-18 12:57:46,377 INFO 2024-07-18 12:57:46,377 INFO ---------------------- 2024-07-18 12:57:46,377 INFO [18/Jul/2024 13:55:54] Update /results/gatling//latest 2024-07-18 12:57:46,377 INFO ---------------------- 2024-07-18 12:57:46,377 INFO 2024-07-18 12:57:46,377 INFO ---------------------- 2024-07-18 12:57:46,377 INFO [18/Jul/2024 13:55:54] Update /results/gatling//latest.tar.gz 2024-07-18 12:57:46,377 INFO ---------------------- 2024-07-18 12:57:46,377 INFO ________________________________________________________________________________ [2024-07-18 12:57:46] benchmark-130 show_stderr : Display stderr order ________________________________________________________________________________ 2024-07-18 12:57:46,378 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/07_benchmark-130/stderr.txt is small (0 lines), printing all lines: 2024-07-18 12:57:46,378 DEBUG ----- output ----- 2024-07-18 12:57:46,378 DEBUG 2024-07-18 12:57:46,378 INFO ________________________________________________________________________________ [2024-07-18 12:57:46] benchmark-130 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 12:57:46] benchmark-130 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-07-18 12:57:46] benchmark-130 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped