--Task-- name: benchmark-70 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-50'] preceding_task: benchmark-50 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self70-', 'testCase': 'idc.benchmark.CH2023', 'testMode': 'open', 'throughput': 70} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-07-18 08:47:12] benchmark-70 pre : N/A ________________________________________________________________________________ 2024-07-18 08:47:12 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-07-18 08:47:12 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-70/stdout.txt Pod log : /results/orders/benchmark-70 2024-07-18 08:47:12,951 INFO 2024-07-18 08:47:12,951 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 08:47:12,951 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 08:47:13,259 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 08:47:13,260 INFO [loop_until]: OK (rc = 0) 2024-07-18 08:47:13,260 DEBUG --- stdout --- 2024-07-18 08:47:13,260 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 08:47:13,260 DEBUG --- stderr --- 2024-07-18 08:47:13,260 DEBUG ________________________________________________________________________________ [2024-07-18 08:47:13] benchmark-70 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-07-18 08:47:13,261 INFO 2024-07-18 08:47:13,261 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 08:47:13,261 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 08:47:13,565 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 08:47:13,566 INFO [loop_until]: OK (rc = 0) 2024-07-18 08:47:13,566 DEBUG --- stdout --- 2024-07-18 08:47:13,566 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 08:47:13,566 DEBUG --- stderr --- 2024-07-18 08:47:13,566 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-70 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-07-18 08:47:13] benchmark-70 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-07-18 08:47:13,567 INFO 2024-07-18 08:47:13,567 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 08:47:13,567 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 08:47:13,876 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 08:47:13,876 INFO [loop_until]: OK (rc = 0) 2024-07-18 08:47:13,878 DEBUG --- stdout --- 2024-07-18 08:47:13,878 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 08:47:13,878 DEBUG --- stderr --- 2024-07-18 08:47:13,878 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/order.json { "name": "benchmark-70", "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-70 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/benchmark-70", "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=self70- -Drealm=alpha -DtestCase=idc.benchmark.CH2023 -DtestMode=open -Dthroughput=70 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-07-18 08:47:13] benchmark-70 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 08:47:13,881 INFO 2024-07-18 08:47:13,881 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-07-18 08:47:13,881 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-18 08:47:14,231 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 08:47:14,231 INFO [loop_until]: OK (rc = 0) 2024-07-18 08:47:14,231 DEBUG --- stdout --- 2024-07-18 08:47:14,231 DEBUG WAITING 2024-07-18 08:47:14,231 DEBUG --- stderr --- 2024-07-18 08:47:14,232 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 22 0 --:--:-- --:--:-- --:--:-- 22 ________________________________________________________________________________ [2024-07-18 08:47:14] benchmark-70 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-07-18 08:47:14,232 INFO 2024-07-18 08:47:14,232 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 08:47:14,232 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 08:47:14,547 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 08:47:14,548 INFO [loop_until]: OK (rc = 0) 2024-07-18 08:47:14,548 DEBUG --- stdout --- 2024-07-18 08:47:14,548 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 08:47:14,548 DEBUG --- stderr --- 2024-07-18 08:47:14,548 DEBUG 2024-07-18 08:47:14,548 INFO 2024-07-18 08:47:14,548 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/04_benchmark-70/order.json overseer-0-7796fc5d9b-ngsxg:/results/orders/order.json 2024-07-18 08:47:14,548 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 08:47:15,924 INFO [loop_until]: OK (rc = 0) 2024-07-18 08:47:15,924 DEBUG --- stdout --- 2024-07-18 08:47:15,924 DEBUG 2024-07-18 08:47:15,924 DEBUG --- stderr --- 2024-07-18 08:47:15,924 DEBUG Order has been successfully forwarded to overseer 2024-07-18 08:47:15 - INFO: Check if Gatling simulation started successfully 2024-07-18 08:47:15,925 INFO 2024-07-18 08:47:15,925 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 08:47:15,925 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 08:47:16,233 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 08:47:16,233 INFO [loop_until]: OK (rc = 0) 2024-07-18 08:47:16,233 DEBUG --- stdout --- 2024-07-18 08:47:16,233 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 08:47:16,233 DEBUG --- stderr --- 2024-07-18 08:47:16,233 DEBUG 2024-07-18 08:47:16,233 INFO 2024-07-18 08:47:16,233 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-70/stdout.txt | grep "started..." 2024-07-18 08:47:16,233 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-18 08:47:16,973 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-07-18 08:47:22,710 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-07-18 08:47:22,711 INFO [loop_until]: OK (rc = 0) 2024-07-18 08:47:22,711 DEBUG --- stdout --- 2024-07-18 08:47:22,711 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 08:47:22,711 DEBUG --- stderr --- 2024-07-18 08:47:22,711 DEBUG ________________________________________________________________________________ [2024-07-18 08:47:22] benchmark-70 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 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 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 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-07-18 09:49:32] benchmark-70 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-07-18 09:49:32,916 INFO 2024-07-18 09:49:32,917 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:32,917 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:33,248 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:33,249 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:33,249 DEBUG --- stdout --- 2024-07-18 09:49:33,249 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:33,249 DEBUG --- stderr --- 2024-07-18 09:49:33,249 DEBUG 2024-07-18 09:49:33,249 INFO 2024-07-18 09:49:33,249 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 09:49:33,249 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 09:49:34,032 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:34,032 DEBUG --- stdout --- 2024-07-18 09:49:34,032 DEBUG 2024-07-18 09:49:34,032 DEBUG --- stderr --- 2024-07-18 09:49:34,032 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-07-18 09:49:34] benchmark-70 download_logs : Download individual order files ________________________________________________________________________________ 2024-07-18 09:49:34 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/order.json 2024-07-18 09:49:34,034 INFO 2024-07-18 09:49:34,034 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:34,034 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:34,385 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:34,386 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:34,386 DEBUG --- stdout --- 2024-07-18 09:49:34,386 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:34,386 DEBUG --- stderr --- 2024-07-18 09:49:34,386 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 09:49:34,386 INFO 2024-07-18 09:49:34,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 09:49:34,386 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:34,710 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:34,710 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:34,710 DEBUG --- stdout --- 2024-07-18 09:49:34,710 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:34,711 DEBUG --- stderr --- 2024-07-18 09:49:34,711 DEBUG 2024-07-18 09:49:34,711 INFO 2024-07-18 09:49:34,711 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-70/rc.txt" ]; then echo "/results/orders/benchmark-70/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-70/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-70/rc.txt file found" 2024-07-18 09:49:34,711 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 09:49:35,525 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 09:49:35,525 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:35,525 DEBUG --- stdout --- 2024-07-18 09:49:35,525 DEBUG /results/orders/benchmark-70/rc.txt file found 2024-07-18 09:49:35,525 DEBUG --- stderr --- 2024-07-18 09:49:35,525 DEBUG 2024-07-18 09:49:35,526 INFO 2024-07-18 09:49:35,526 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:35,526 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:35,833 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:35,833 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:35,833 DEBUG --- stdout --- 2024-07-18 09:49:35,834 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:35,834 DEBUG --- stderr --- 2024-07-18 09:49:35,834 DEBUG 2024-07-18 09:49:35,834 INFO 2024-07-18 09:49:35,834 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-70/rc.txt 2024-07-18 09:49:36,652 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 09:49:36,652 DEBUG --- stdout --- 2024-07-18 09:49:36,652 DEBUG 4 /results/orders/benchmark-70/rc.txt 2024-07-18 09:49:36,652 DEBUG --- stderr --- 2024-07-18 09:49:36,653 DEBUG 2024-07-18 09:49:36,653 INFO - Download file (size 4k) 2024-07-18 09:49:36,653 INFO 2024-07-18 09:49:36,653 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:36,653 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:36,958 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:36,959 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:36,959 DEBUG --- stdout --- 2024-07-18 09:49:36,959 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:36,959 DEBUG --- stderr --- 2024-07-18 09:49:36,959 DEBUG source : /results/orders/benchmark-70/rc.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/rc.txt 2024-07-18 09:49:36,959 INFO 2024-07-18 09:49:36,959 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:36,959 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:37,263 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:37,263 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:37,263 DEBUG --- stdout --- 2024-07-18 09:49:37,263 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:37,263 DEBUG --- stderr --- 2024-07-18 09:49:37,263 DEBUG 2024-07-18 09:49:37,264 INFO 2024-07-18 09:49:37,264 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-70/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/rc.txt 2024-07-18 09:49:37,264 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 09:49:37,998 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:37,998 DEBUG --- stdout --- 2024-07-18 09:49:37,998 DEBUG tar: Removing leading `/' from member names 2024-07-18 09:49:37,998 DEBUG --- stderr --- 2024-07-18 09:49:37,998 DEBUG 2024-07-18 09:49:37,999 INFO 2024-07-18 09:49:37,999 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:37,999 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:38,317 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:38,317 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:38,317 DEBUG --- stdout --- 2024-07-18 09:49:38,318 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:38,318 DEBUG --- stderr --- 2024-07-18 09:49:38,318 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 09:49:38,318 INFO 2024-07-18 09:49:38,318 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:38,318 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:38,639 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:38,639 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:38,639 DEBUG --- stdout --- 2024-07-18 09:49:38,639 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:38,639 DEBUG --- stderr --- 2024-07-18 09:49:38,639 DEBUG 2024-07-18 09:49:38,640 INFO 2024-07-18 09:49:38,640 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-70/stderr.txt" ]; then echo "/results/orders/benchmark-70/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-70/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-70/stderr.txt file found" 2024-07-18 09:49:38,640 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 09:49:39,453 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 09:49:39,454 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:39,454 DEBUG --- stdout --- 2024-07-18 09:49:39,454 DEBUG /results/orders/benchmark-70/stderr.txt file found 2024-07-18 09:49:39,454 DEBUG --- stderr --- 2024-07-18 09:49:39,454 DEBUG 2024-07-18 09:49:39,454 INFO 2024-07-18 09:49:39,454 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:39,454 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:39,760 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:39,761 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:39,761 DEBUG --- stdout --- 2024-07-18 09:49:39,761 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:39,761 DEBUG --- stderr --- 2024-07-18 09:49:39,761 DEBUG 2024-07-18 09:49:39,761 INFO 2024-07-18 09:49:39,761 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-70/stderr.txt 2024-07-18 09:49:40,587 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 09:49:40,587 DEBUG --- stdout --- 2024-07-18 09:49:40,587 DEBUG 0 /results/orders/benchmark-70/stderr.txt 2024-07-18 09:49:40,587 DEBUG --- stderr --- 2024-07-18 09:49:40,587 DEBUG 2024-07-18 09:49:40,587 INFO - Download file (size 0k) 2024-07-18 09:49:40,588 INFO 2024-07-18 09:49:40,588 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:40,588 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:40,903 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:40,904 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:40,904 DEBUG --- stdout --- 2024-07-18 09:49:40,904 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:40,904 DEBUG --- stderr --- 2024-07-18 09:49:40,904 DEBUG source : /results/orders/benchmark-70/stderr.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/stderr.txt 2024-07-18 09:49:40,904 INFO 2024-07-18 09:49: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 09:49:40,904 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:41,211 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:41,211 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:41,211 DEBUG --- stdout --- 2024-07-18 09:49:41,211 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:41,211 DEBUG --- stderr --- 2024-07-18 09:49:41,211 DEBUG 2024-07-18 09:49:41,211 INFO 2024-07-18 09:49:41,212 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-70/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/stderr.txt 2024-07-18 09:49:41,212 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 09:49:41,944 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:41,944 DEBUG --- stdout --- 2024-07-18 09:49:41,945 DEBUG tar: Removing leading `/' from member names 2024-07-18 09:49:41,945 DEBUG --- stderr --- 2024-07-18 09:49:41,945 DEBUG 2024-07-18 09:49:41,945 INFO 2024-07-18 09:49:41,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 09:49:41,945 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:42,254 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:42,254 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:42,254 DEBUG --- stdout --- 2024-07-18 09:49:42,254 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:42,254 DEBUG --- stderr --- 2024-07-18 09:49:42,254 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 09:49:42,254 INFO 2024-07-18 09:49:42,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 09:49:42,255 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:42,560 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:42,560 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:42,560 DEBUG --- stdout --- 2024-07-18 09:49:42,560 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:42,560 DEBUG --- stderr --- 2024-07-18 09:49:42,560 DEBUG 2024-07-18 09:49:42,561 INFO 2024-07-18 09:49:42,561 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-70/stdout.txt" ]; then echo "/results/orders/benchmark-70/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-70/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-70/stdout.txt file found" 2024-07-18 09:49:42,561 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 09:49:43,369 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 09:49:43,369 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:43,369 DEBUG --- stdout --- 2024-07-18 09:49:43,369 DEBUG /results/orders/benchmark-70/stdout.txt file found 2024-07-18 09:49:43,369 DEBUG --- stderr --- 2024-07-18 09:49:43,369 DEBUG 2024-07-18 09:49:43,370 INFO 2024-07-18 09:49:43,370 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:43,370 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:43,690 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:43,690 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:43,690 DEBUG --- stdout --- 2024-07-18 09:49:43,691 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:43,691 DEBUG --- stderr --- 2024-07-18 09:49:43,691 DEBUG 2024-07-18 09:49:43,691 INFO 2024-07-18 09:49:43,691 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-70/stdout.txt 2024-07-18 09:49:44,506 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 09:49:44,506 DEBUG --- stdout --- 2024-07-18 09:49:44,506 DEBUG 2912 /results/orders/benchmark-70/stdout.txt 2024-07-18 09:49:44,507 DEBUG --- stderr --- 2024-07-18 09:49:44,507 DEBUG 2024-07-18 09:49:44,507 INFO - Download file (size 2912k) 2024-07-18 09:49:44,507 INFO 2024-07-18 09:49:44,507 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:44,507 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:44,815 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:44,815 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:44,815 DEBUG --- stdout --- 2024-07-18 09:49:44,815 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:44,815 DEBUG --- stderr --- 2024-07-18 09:49:44,815 DEBUG source : /results/orders/benchmark-70/stdout.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/stdout.txt 2024-07-18 09:49:44,816 INFO 2024-07-18 09:49:44,816 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:44,816 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:45,140 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:45,140 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:45,140 DEBUG --- stdout --- 2024-07-18 09:49:45,140 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:45,140 DEBUG --- stderr --- 2024-07-18 09:49:45,141 DEBUG 2024-07-18 09:49:45,141 INFO 2024-07-18 09:49:45,141 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-70/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/stdout.txt 2024-07-18 09:49:45,141 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 09:49:46,358 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:46,359 DEBUG --- stdout --- 2024-07-18 09:49:46,359 DEBUG tar: Removing leading `/' from member names 2024-07-18 09:49:46,359 DEBUG --- stderr --- 2024-07-18 09:49:46,359 DEBUG ________________________________________________________________________________ [2024-07-18 09:49:46] benchmark-70 show_cmd : Display order command ________________________________________________________________________________ 2024-07-18 09:49:46,360 INFO 2024-07-18 09:49:46,360 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:46,360 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:46,675 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:46,675 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:46,676 DEBUG --- stdout --- 2024-07-18 09:49:46,676 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:46,676 DEBUG --- stderr --- 2024-07-18 09:49:46,676 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 09:49:46,676 INFO 2024-07-18 09:49:46,676 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:46,676 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:46,997 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:46,997 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:46,999 DEBUG --- stdout --- 2024-07-18 09:49:46,999 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:46,999 DEBUG --- stderr --- 2024-07-18 09:49:46,999 DEBUG 2024-07-18 09:49:47,000 INFO 2024-07-18 09:49:47,000 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-70/cmd.txt" ]; then echo "/results/orders/benchmark-70/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-70/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-70/cmd.txt file found" 2024-07-18 09:49:47,000 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 09:49:47,829 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 09:49:47,830 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:47,830 DEBUG --- stdout --- 2024-07-18 09:49:47,830 DEBUG /results/orders/benchmark-70/cmd.txt file found 2024-07-18 09:49:47,830 DEBUG --- stderr --- 2024-07-18 09:49:47,830 DEBUG 2024-07-18 09:49:47,830 INFO 2024-07-18 09:49:47,830 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:47,830 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:48,147 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:48,148 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:48,148 DEBUG --- stdout --- 2024-07-18 09:49:48,148 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:48,148 DEBUG --- stderr --- 2024-07-18 09:49:48,148 DEBUG 2024-07-18 09:49:48,148 INFO 2024-07-18 09:49:48,148 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-70/cmd.txt 2024-07-18 09:49:48,962 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 09:49:48,962 DEBUG --- stdout --- 2024-07-18 09:49:48,962 DEBUG 4 /results/orders/benchmark-70/cmd.txt 2024-07-18 09:49:48,962 DEBUG --- stderr --- 2024-07-18 09:49:48,962 DEBUG 2024-07-18 09:49:48,962 INFO - Download file (size 4k) 2024-07-18 09:49:48,963 INFO 2024-07-18 09:49:48,963 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:48,963 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:49,274 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:49,275 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:49,275 DEBUG --- stdout --- 2024-07-18 09:49:49,275 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:49,275 DEBUG --- stderr --- 2024-07-18 09:49:49,275 DEBUG source : /results/orders/benchmark-70/cmd.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/cmd.txt 2024-07-18 09:49:49,275 INFO 2024-07-18 09:49:49,275 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 09:49:49,275 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 09:49:49,582 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 09:49:49,582 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:49,582 DEBUG --- stdout --- 2024-07-18 09:49:49,582 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 09:49:49,582 DEBUG --- stderr --- 2024-07-18 09:49:49,582 DEBUG 2024-07-18 09:49:49,582 INFO 2024-07-18 09:49:49,582 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-70/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/cmd.txt 2024-07-18 09:49:49,583 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 09:49:50,363 INFO [loop_until]: OK (rc = 0) 2024-07-18 09:49:50,364 DEBUG --- stdout --- 2024-07-18 09:49:50,364 DEBUG tar: Removing leading `/' from member names 2024-07-18 09:49:50,364 DEBUG --- stderr --- 2024-07-18 09:49:50,364 DEBUG 2024-07-18 09:49:50,364 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/cmd.txt is small (1 lines), printing all lines: 2024-07-18 09:49:50,365 DEBUG ----- output ----- 2024-07-18 09:49:50,365 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-70 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-07-18 09:49:50,365 INFO ________________________________________________________________________________ [2024-07-18 09:49:50] benchmark-70 show_rc : Display rc order ________________________________________________________________________________ 2024-07-18 09:49:50,365 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/rc.txt is small (1 lines), printing all lines: 2024-07-18 09:49:50,365 DEBUG ----- output ----- 2024-07-18 09:49:50,365 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-07-18 09:49:50,365 INFO ________________________________________________________________________________ [2024-07-18 09:49:50] benchmark-70 show_stdout : Display stdout order ________________________________________________________________________________ 2024-07-18 09:49:50,371 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/04_benchmark-70/stdout.txt is 39796 lines): 2024-07-18 09:49:50,372 DEBUG ----- output ----- 2024-07-18 09:49:50,372 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-07-18 09:49:50,372 INFO Gatling 3.11.5 is available! (you're using 3.9.5) 2024-07-18 09:49:50,372 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-07-18 09:49:50,372 INFO 2024-07-18 09:49:50,372 INFO ================================================================================ 2024-07-18 09:49:50,372 INFO 2024-07-18 09:47:24 5s elapsed 2024-07-18 09:49:50,372 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 09:49:50,372 INFO > Global (OK=202 KO=0 ) 2024-07-18 09:49:50,372 INFO > restLoginInitiate (OK=26 KO=0 ) 2024-07-18 09:49:50,372 INFO > restLoginUsernamePasswordCallback (OK=25 KO=0 ) 2024-07-18 09:49:50,372 INFO > User Authorize PKCE stage (OK=25 KO=0 ) 2024-07-18 09:49:50,372 INFO > User AccessToken PKCE stage (OK=25 KO=0 ) 2024-07-18 09:49:50,372 INFO > tokenUserinfo (OK=7 KO=0 ) 2024-07-18 09:49:50,372 INFO > registration_restLoginInitiate (OK=3 KO=0 ) 2024-07-18 09:49:50,372 INFO > registrationCallback (OK=3 KO=0 ) 2024-07-18 09:49:50,372 INFO > Skip 2FA (OK=16 KO=0 ) 2024-07-18 09:49:50,372 INFO > RootUserinfo (OK=18 KO=0 ) 2024-07-18 09:49:50,372 INFO > getIdToken (OK=7 KO=0 ) 2024-07-18 09:49:50,372 INFO > getIdToken Redirect 1 (OK=6 KO=0 ) 2024-07-18 09:49:50,372 INFO > createOrganization (OK=6 KO=0 ) 2024-07-18 09:49:50,372 INFO > queryOrganization (OK=6 KO=0 ) 2024-07-18 09:49:50,372 INFO > deleteOrganization (OK=6 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI-Post-Login (OK=1 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI-Post-Login Redirect 1 (OK=3 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI AM validateGoto (OK=1 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI AM idFromSession (OK=1 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI Enduser appAuthHelper2 (OK=1 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI Enduser appAuthHelper1 (OK=1 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI Enduser sessioncheck (OK=1 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI IDM uiconfig (OK=1 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI IDM version (OK=1 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI IDM login (OK=1 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI IDM privilege (OK=1 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI IDM features (OK=1 KO=0 ) 2024-07-18 09:49:50,372 INFO > UI IDM dashboard (OK=1 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI IDM user (OK=1 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI IDM alpha_user (OK=1 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI IDM oidcToken (OK=1 KO=0 ) 2024-07-18 09:49:50,373 INFO > userinfo (OK=1 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI-Logout Redirect 1 (OK=1 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI AM revoke2 (OK=1 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI AM revoke1 (OK=1 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI AM endsession (OK=1 KO=0 ) 2024-07-18 09:49:50,373 INFO 2024-07-18 09:49:50,373 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 09:49:50,373 INFO [- ] 0% 2024-07-18 09:49:50,373 INFO waiting: 252780 / active: 13 / done: 17 2024-07-18 09:49:50,373 INFO ================================================================================ 2024-07-18 09:49:50,373 INFO 2024-07-18 09:49:50,373 INFO 2024-07-18 09:49:50,373 INFO ================================================================================ 2024-07-18 09:49:50,373 INFO 2024-07-18 09:47:29 10s elapsed 2024-07-18 09:49:50,373 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 09:49:50,373 INFO > Global (OK=875 KO=0 ) 2024-07-18 09:49:50,373 INFO > restLoginInitiate (OK=100 KO=0 ) 2024-07-18 09:49:50,373 INFO > restLoginUsernamePasswordCallback (OK=99 KO=0 ) 2024-07-18 09:49:50,373 INFO > User Authorize PKCE stage (OK=101 KO=0 ) 2024-07-18 09:49:50,373 INFO > User AccessToken PKCE stage (OK=101 KO=0 ) 2024-07-18 09:49:50,373 INFO > tokenUserinfo (OK=24 KO=0 ) 2024-07-18 09:49:50,373 INFO > registration_restLoginInitiate (OK=16 KO=0 ) 2024-07-18 09:49:50,373 INFO > registrationCallback (OK=15 KO=0 ) 2024-07-18 09:49:50,373 INFO > Skip 2FA (OK=68 KO=0 ) 2024-07-18 09:49:50,373 INFO > RootUserinfo (OK=139 KO=0 ) 2024-07-18 09:49:50,373 INFO > getIdToken (OK=19 KO=0 ) 2024-07-18 09:49:50,373 INFO > getIdToken Redirect 1 (OK=19 KO=0 ) 2024-07-18 09:49:50,373 INFO > createOrganization (OK=19 KO=0 ) 2024-07-18 09:49:50,373 INFO > queryOrganization (OK=19 KO=0 ) 2024-07-18 09:49:50,373 INFO > deleteOrganization (OK=19 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI-Post-Login (OK=6 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI-Post-Login Redirect 1 (OK=16 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI AM validateGoto (OK=5 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI AM idFromSession (OK=5 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI Enduser appAuthHelper2 (OK=5 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI Enduser appAuthHelper1 (OK=5 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI Enduser sessioncheck (OK=5 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI IDM uiconfig (OK=5 KO=0 ) 2024-07-18 09:49:50,373 INFO > UI IDM version (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > UI IDM login (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > UI IDM privilege (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > UI IDM features (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > UI IDM dashboard (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > UI IDM user (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > UI IDM alpha_user (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > UI IDM oidcToken (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > userinfo (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > UI-Logout Redirect 1 (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > UI AM revoke2 (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > UI AM revoke1 (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO > UI AM endsession (OK=5 KO=0 ) 2024-07-18 09:49:50,374 INFO 2024-07-18 09:49:50,374 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 09:49:50,374 INFO [- ] 0% 2024-07-18 09:49:50,374 INFO waiting: 252693 / active: 44 / done: 73 2024-07-18 09:49:50,374 INFO ================================================================================ 2024-07-18 09:49:50,374 INFO 2024-07-18 09:49:50,374 INFO 2024-07-18 09:49:50,374 INFO ================================================================================ 2024-07-18 09:49:50,374 INFO 2024-07-18 09:47:34 15s elapsed 2024-07-18 09:49:50,374 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 09:49:50,374 INFO > Global (OK=1961 KO=0 ) 2024-07-18 09:49:50,374 INFO [...] 2024-07-18 09:49:50,384 INFO > UI IDM privilege (OK=10228 KO=1 ) 2024-07-18 09:49:50,385 INFO > UI IDM features (OK=10229 KO=0 ) 2024-07-18 09:49:50,385 INFO > UI IDM dashboard (OK=10229 KO=0 ) 2024-07-18 09:49:50,385 INFO > UI IDM user (OK=10229 KO=0 ) 2024-07-18 09:49:50,385 INFO > UI IDM alpha_user (OK=10229 KO=0 ) 2024-07-18 09:49:50,385 INFO > UI IDM oidcToken (OK=10229 KO=0 ) 2024-07-18 09:49:50,385 INFO > userinfo (OK=10229 KO=0 ) 2024-07-18 09:49:50,385 INFO > UI-Logout Redirect 1 (OK=10229 KO=0 ) 2024-07-18 09:49:50,385 INFO > UI AM revoke2 (OK=10229 KO=0 ) 2024-07-18 09:49:50,385 INFO > UI AM revoke1 (OK=10229 KO=0 ) 2024-07-18 09:49:50,385 INFO > UI AM endsession (OK=10229 KO=0 ) 2024-07-18 09:49:50,385 INFO > restloginProgressiveProfileCallback (OK=57 KO=0 ) 2024-07-18 09:49:50,385 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 09:49:50,385 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 278 (94.24%) 2024-07-18 09:49:50,385 INFO cess_token' is defined 2024-07-18 09:49:50,385 INFO > RootUserinfo: Failed to build request: No attribute named 'acc 10 ( 3.39%) 2024-07-18 09:49:50,385 INFO ess_token' is defined 2024-07-18 09:49:50,385 INFO > status.find.is(200), but actually found 502 3 ( 1.02%) 2024-07-18 09:49:50,385 INFO > status.find.in([200, 209], 304), found 502 2 ( 0.68%) 2024-07-18 09:49:50,385 INFO > status.find.is(302), but actually found 502 2 ( 0.68%) 2024-07-18 09:49:50,385 INFO 2024-07-18 09:49:50,385 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 09:49:50,385 INFO [#########################################################################-] 99% 2024-07-18 09:49:50,385 INFO waiting: 0 / active: 31 / done: 252779 2024-07-18 09:49:50,385 INFO ================================================================================ 2024-07-18 09:49:50,385 INFO 2024-07-18 09:49:50,385 INFO 2024-07-18 09:49:50,385 INFO ================================================================================ 2024-07-18 09:49:50,385 INFO 2024-07-18 10:47:57 3637s elapsed 2024-07-18 09:49:50,385 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 09:49:50,385 INFO > Global (OK=2143615 KO=7 ) 2024-07-18 09:49:50,385 INFO > restLoginInitiate (OK=212150 KO=1 ) 2024-07-18 09:49:50,385 INFO > restLoginUsernamePasswordCallback (OK=212148 KO=2 ) 2024-07-18 09:49:50,385 INFO > User Authorize PKCE stage (OK=222098 KO=2 ) 2024-07-18 09:49:50,385 INFO > User AccessToken PKCE stage (OK=222097 KO=1 ) 2024-07-18 09:49:50,385 INFO > tokenUserinfo (OK=50271 KO=0 ) 2024-07-18 09:49:50,385 INFO > registration_restLoginInitiate (OK=40659 KO=0 ) 2024-07-18 09:49:50,385 INFO > registrationCallback (OK=40659 KO=0 ) 2024-07-18 09:49:50,385 INFO > Skip 2FA (OK=151370 KO=0 ) 2024-07-18 09:49:50,385 INFO > RootUserinfo (OK=607050 KO=0 ) 2024-07-18 09:49:50,385 INFO > getIdToken (OK=29958 KO=0 ) 2024-07-18 09:49:50,386 INFO > getIdToken Redirect 1 (OK=29958 KO=0 ) 2024-07-18 09:49:50,386 INFO > createOrganization (OK=29958 KO=0 ) 2024-07-18 09:49:50,386 INFO > queryOrganization (OK=29958 KO=0 ) 2024-07-18 09:49:50,386 INFO > deleteOrganization (OK=29958 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI-Post-Login (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI-Post-Login Redirect 1 (OK=30687 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI AM validateGoto (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI AM idFromSession (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI Enduser appAuthHelper2 (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI Enduser appAuthHelper1 (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI Enduser sessioncheck (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI IDM uiconfig (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI IDM version (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI IDM login (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI IDM privilege (OK=10228 KO=1 ) 2024-07-18 09:49:50,386 INFO > UI IDM features (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI IDM dashboard (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI IDM user (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI IDM alpha_user (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI IDM oidcToken (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > userinfo (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI-Logout Redirect 1 (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI AM revoke2 (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI AM revoke1 (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > UI AM endsession (OK=10229 KO=0 ) 2024-07-18 09:49:50,386 INFO > restloginProgressiveProfileCallback (OK=57 KO=0 ) 2024-07-18 09:49:50,387 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 09:49:50,387 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 278 (94.24%) 2024-07-18 09:49:50,387 INFO cess_token' is defined 2024-07-18 09:49:50,387 INFO > RootUserinfo: Failed to build request: No attribute named 'acc 10 ( 3.39%) 2024-07-18 09:49:50,387 INFO ess_token' is defined 2024-07-18 09:49:50,387 INFO > status.find.is(200), but actually found 502 3 ( 1.02%) 2024-07-18 09:49:50,387 INFO > status.find.in([200, 209], 304), found 502 2 ( 0.68%) 2024-07-18 09:49:50,387 INFO > status.find.is(302), but actually found 502 2 ( 0.68%) 2024-07-18 09:49:50,387 INFO 2024-07-18 09:49:50,387 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 09:49:50,387 INFO [##########################################################################]100% 2024-07-18 09:49:50,387 INFO waiting: 0 / active: 0 / done: 252810 2024-07-18 09:49:50,387 INFO ================================================================================ 2024-07-18 09:49:50,387 INFO 2024-07-18 09:49:50,387 INFO Simulation com.forgerock.gatling.Loadtest completed in 3637 seconds 2024-07-18 09:49:50,387 INFO 2024-07-18 09:49:50,387 INFO ---------------------- 2024-07-18 09:49:50,387 INFO [18/Jul/2024 10:47:57] Create tar file benchmark-70.tar.gz 2024-07-18 09:49:50,387 INFO ---------------------- 2024-07-18 09:49:50,387 INFO RESULTS_FOLDER=/results/gatling/ 2024-07-18 09:49:50,387 INFO RESULT_FOLDER=benchmark-70 2024-07-18 09:49:50,387 INFO SIMULATION_PATH=benchmark-70/loadtest-20240718084719372 2024-07-18 09:49:50,387 INFO PWD=/results/gatling/benchmark-70/loadtest-20240718084719372 2024-07-18 09:49:50,387 INFO [cmd] tar -czvf /results/gatling//benchmark-70.tar.gz --exclude=simulation.log * 2024-07-18 09:49:50,387 INFO /results/gatling 2024-07-18 09:49:50,387 INFO 2024-07-18 09:49:50,387 INFO ---------------------- 2024-07-18 09:49:50,387 INFO [18/Jul/2024 10:47:57] Update /results/gatling//latest 2024-07-18 09:49:50,387 INFO ---------------------- 2024-07-18 09:49:50,387 INFO 2024-07-18 09:49:50,387 INFO ---------------------- 2024-07-18 09:49:50,387 INFO [18/Jul/2024 10:47:57] Update /results/gatling//latest.tar.gz 2024-07-18 09:49:50,387 INFO ---------------------- 2024-07-18 09:49:50,387 INFO ________________________________________________________________________________ [2024-07-18 09:49:50] benchmark-70 show_stderr : Display stderr order ________________________________________________________________________________ 2024-07-18 09:49:50,388 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/04_benchmark-70/stderr.txt is small (0 lines), printing all lines: 2024-07-18 09:49:50,388 DEBUG ----- output ----- 2024-07-18 09:49:50,388 DEBUG 2024-07-18 09:49:50,388 INFO ________________________________________________________________________________ [2024-07-18 09:49:50] benchmark-70 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 09:49:50] benchmark-70 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-07-18 09:49:50] benchmark-70 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped