--Task-- name: benchmark-110 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-90'] preceding_task: benchmark-90 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self110-', 'testCase': 'idc.benchmark.CH2023', 'testMode': 'open', 'throughput': 110} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-07-18 10:52:30] benchmark-110 pre : N/A ________________________________________________________________________________ 2024-07-18 10:52:30 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-07-18 10:52:30 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-110/stdout.txt Pod log : /results/orders/benchmark-110 2024-07-18 10:52:30,048 INFO 2024-07-18 10:52:30,048 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 10:52:30,049 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:30,377 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:30,377 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:30,377 DEBUG --- stdout --- 2024-07-18 10:52:30,377 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:30,377 DEBUG --- stderr --- 2024-07-18 10:52:30,377 DEBUG ________________________________________________________________________________ [2024-07-18 10:52:30] benchmark-110 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-07-18 10:52:30,378 INFO 2024-07-18 10:52:30,378 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 10:52:30,378 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:30,705 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:30,705 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:30,706 DEBUG --- stdout --- 2024-07-18 10:52:30,706 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:30,706 DEBUG --- stderr --- 2024-07-18 10:52:30,706 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-110 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-07-18 10:52:30] benchmark-110 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-07-18 10:52:30,707 INFO 2024-07-18 10:52:30,707 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 10:52:30,707 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:31,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:31,029 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:31,029 DEBUG --- stdout --- 2024-07-18 10:52:31,029 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:31,029 DEBUG --- stderr --- 2024-07-18 10:52:31,029 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/order.json { "name": "benchmark-110", "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-110 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/benchmark-110", "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=self110- -Drealm=alpha -DtestCase=idc.benchmark.CH2023 -DtestMode=open -Dthroughput=110 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-07-18 10:52:31] benchmark-110 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 10:52:31,032 INFO 2024-07-18 10:52:31,032 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-07-18 10:52:31,032 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-18 10:52:31,291 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 10:52:31,292 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:31,292 DEBUG --- stdout --- 2024-07-18 10:52:31,292 DEBUG WAITING 2024-07-18 10:52:31,292 DEBUG --- stderr --- 2024-07-18 10:52:31,292 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 10:52:31] benchmark-110 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-07-18 10:52:31,293 INFO 2024-07-18 10:52:31,293 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 10:52:31,293 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:31,615 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:31,615 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:31,615 DEBUG --- stdout --- 2024-07-18 10:52:31,615 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:31,615 DEBUG --- stderr --- 2024-07-18 10:52:31,615 DEBUG 2024-07-18 10:52:31,615 INFO 2024-07-18 10:52:31,615 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/06_benchmark-110/order.json overseer-0-7796fc5d9b-ngsxg:/results/orders/order.json 2024-07-18 10:52:31,616 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 10:52:33,004 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:33,004 DEBUG --- stdout --- 2024-07-18 10:52:33,004 DEBUG 2024-07-18 10:52:33,004 DEBUG --- stderr --- 2024-07-18 10:52:33,004 DEBUG Order has been successfully forwarded to overseer 2024-07-18 10:52:33 - INFO: Check if Gatling simulation started successfully 2024-07-18 10:52:33,005 INFO 2024-07-18 10:52:33,005 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 10:52:33,005 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 10:52:33,323 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 10:52:33,323 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:33,323 DEBUG --- stdout --- 2024-07-18 10:52:33,323 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 10:52:33,324 DEBUG --- stderr --- 2024-07-18 10:52:33,324 DEBUG 2024-07-18 10:52:33,324 INFO 2024-07-18 10:52:33,324 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-110/stdout.txt | grep "started..." 2024-07-18 10:52:33,324 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-18 10:52:34,095 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-07-18 10:52:39,879 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-07-18 10:52:39,879 INFO [loop_until]: OK (rc = 0) 2024-07-18 10:52:39,880 DEBUG --- stdout --- 2024-07-18 10:52:39,880 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 10:52:39,880 DEBUG --- stderr --- 2024-07-18 10:52:39,880 DEBUG ________________________________________________________________________________ [2024-07-18 10:52:39] benchmark-110 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-07-18 11:54:50] benchmark-110 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-07-18 11:54:50,413 INFO 2024-07-18 11:54:50,413 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:54:50,413 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:50,745 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:50,746 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:50,746 DEBUG --- stdout --- 2024-07-18 11:54:50,746 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:50,746 DEBUG --- stderr --- 2024-07-18 11:54:50,746 DEBUG 2024-07-18 11:54:50,746 INFO 2024-07-18 11:54:50,746 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 11:54:50,746 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 11:54:51,537 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:51,537 DEBUG --- stdout --- 2024-07-18 11:54:51,537 DEBUG 2024-07-18 11:54:51,537 DEBUG --- stderr --- 2024-07-18 11:54:51,537 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-07-18 11:54:51] benchmark-110 download_logs : Download individual order files ________________________________________________________________________________ 2024-07-18 11:54:51 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/order.json 2024-07-18 11:54:51,539 INFO 2024-07-18 11:54:51,539 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:54:51,539 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:51,844 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:51,845 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:51,845 DEBUG --- stdout --- 2024-07-18 11:54:51,845 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:51,845 DEBUG --- stderr --- 2024-07-18 11:54:51,845 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 11:54:51,845 INFO 2024-07-18 11:54:51,845 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:54:51,845 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:52,160 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:52,161 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:52,161 DEBUG --- stdout --- 2024-07-18 11:54:52,161 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:52,161 DEBUG --- stderr --- 2024-07-18 11:54:52,161 DEBUG 2024-07-18 11:54:52,162 INFO 2024-07-18 11:54:52,162 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-110/rc.txt" ]; then echo "/results/orders/benchmark-110/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-110/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-110/rc.txt file found" 2024-07-18 11:54:52,162 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 11:54:52,977 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 11:54:52,977 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:52,977 DEBUG --- stdout --- 2024-07-18 11:54:52,977 DEBUG /results/orders/benchmark-110/rc.txt file found 2024-07-18 11:54:52,977 DEBUG --- stderr --- 2024-07-18 11:54:52,977 DEBUG 2024-07-18 11:54:52,978 INFO 2024-07-18 11:54:52,978 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:54:52,978 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:53,302 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:53,303 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:53,304 DEBUG --- stdout --- 2024-07-18 11:54:53,304 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:53,305 DEBUG --- stderr --- 2024-07-18 11:54:53,305 DEBUG 2024-07-18 11:54:53,305 INFO 2024-07-18 11:54:53,305 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-110/rc.txt 2024-07-18 11:54:54,128 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 11:54:54,128 DEBUG --- stdout --- 2024-07-18 11:54:54,129 DEBUG 4 /results/orders/benchmark-110/rc.txt 2024-07-18 11:54:54,129 DEBUG --- stderr --- 2024-07-18 11:54:54,129 DEBUG 2024-07-18 11:54:54,129 INFO - Download file (size 4k) 2024-07-18 11:54:54,129 INFO 2024-07-18 11:54:54,129 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:54:54,129 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:54,446 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:54,446 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:54,446 DEBUG --- stdout --- 2024-07-18 11:54:54,446 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:54,446 DEBUG --- stderr --- 2024-07-18 11:54:54,446 DEBUG source : /results/orders/benchmark-110/rc.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/rc.txt 2024-07-18 11:54:54,447 INFO 2024-07-18 11:54:54,447 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:54:54,447 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:54,774 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:54,775 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:54,775 DEBUG --- stdout --- 2024-07-18 11:54:54,775 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:54,775 DEBUG --- stderr --- 2024-07-18 11:54:54,775 DEBUG 2024-07-18 11:54:54,775 INFO 2024-07-18 11:54:54,775 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-110/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/rc.txt 2024-07-18 11:54:54,775 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 11:54:55,561 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:55,561 DEBUG --- stdout --- 2024-07-18 11:54:55,561 DEBUG tar: Removing leading `/' from member names 2024-07-18 11:54:55,561 DEBUG --- stderr --- 2024-07-18 11:54:55,561 DEBUG 2024-07-18 11:54:55,562 INFO 2024-07-18 11:54:55,562 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:54:55,562 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:55,867 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:55,868 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:55,868 DEBUG --- stdout --- 2024-07-18 11:54:55,868 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:55,868 DEBUG --- stderr --- 2024-07-18 11:54:55,868 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 11:54:55,868 INFO 2024-07-18 11:54:55,868 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:54:55,868 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:56,193 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:56,194 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:56,194 DEBUG --- stdout --- 2024-07-18 11:54:56,194 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:56,194 DEBUG --- stderr --- 2024-07-18 11:54:56,194 DEBUG 2024-07-18 11:54:56,194 INFO 2024-07-18 11:54:56,194 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-110/stderr.txt" ]; then echo "/results/orders/benchmark-110/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-110/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-110/stderr.txt file found" 2024-07-18 11:54:56,195 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 11:54:57,013 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 11:54:57,013 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:57,013 DEBUG --- stdout --- 2024-07-18 11:54:57,013 DEBUG /results/orders/benchmark-110/stderr.txt file found 2024-07-18 11:54:57,013 DEBUG --- stderr --- 2024-07-18 11:54:57,013 DEBUG 2024-07-18 11:54:57,014 INFO 2024-07-18 11:54:57,014 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:54:57,014 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:57,330 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:57,330 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:57,331 DEBUG --- stdout --- 2024-07-18 11:54:57,331 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:57,331 DEBUG --- stderr --- 2024-07-18 11:54:57,331 DEBUG 2024-07-18 11:54:57,331 INFO 2024-07-18 11:54:57,331 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-110/stderr.txt 2024-07-18 11:54:58,148 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 11:54:58,148 DEBUG --- stdout --- 2024-07-18 11:54:58,148 DEBUG 0 /results/orders/benchmark-110/stderr.txt 2024-07-18 11:54:58,148 DEBUG --- stderr --- 2024-07-18 11:54:58,148 DEBUG 2024-07-18 11:54:58,148 INFO - Download file (size 0k) 2024-07-18 11:54:58,149 INFO 2024-07-18 11:54:58,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:54:58,149 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:58,465 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:58,465 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:58,465 DEBUG --- stdout --- 2024-07-18 11:54:58,465 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:58,465 DEBUG --- stderr --- 2024-07-18 11:54:58,465 DEBUG source : /results/orders/benchmark-110/stderr.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/stderr.txt 2024-07-18 11:54:58,465 INFO 2024-07-18 11:54:58,466 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:54:58,466 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:58,781 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:58,782 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:58,782 DEBUG --- stdout --- 2024-07-18 11:54:58,782 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:58,782 DEBUG --- stderr --- 2024-07-18 11:54:58,782 DEBUG 2024-07-18 11:54:58,782 INFO 2024-07-18 11:54:58,782 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-110/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/stderr.txt 2024-07-18 11:54:58,782 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 11:54:59,568 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:59,569 DEBUG --- stdout --- 2024-07-18 11:54:59,569 DEBUG tar: Removing leading `/' from member names 2024-07-18 11:54:59,569 DEBUG --- stderr --- 2024-07-18 11:54:59,569 DEBUG 2024-07-18 11:54:59,569 INFO 2024-07-18 11:54:59,569 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:54:59,569 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:54:59,871 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:54:59,871 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:54:59,871 DEBUG --- stdout --- 2024-07-18 11:54:59,871 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:54:59,871 DEBUG --- stderr --- 2024-07-18 11:54:59,871 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 11:54:59,872 INFO 2024-07-18 11:54:59,872 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:54:59,872 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:00,171 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:00,171 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:00,171 DEBUG --- stdout --- 2024-07-18 11:55:00,171 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:00,171 DEBUG --- stderr --- 2024-07-18 11:55:00,171 DEBUG 2024-07-18 11:55:00,172 INFO 2024-07-18 11:55:00,172 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-110/stdout.txt" ]; then echo "/results/orders/benchmark-110/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-110/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-110/stdout.txt file found" 2024-07-18 11:55:00,172 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 11:55:00,973 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 11:55:00,973 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:00,973 DEBUG --- stdout --- 2024-07-18 11:55:00,974 DEBUG /results/orders/benchmark-110/stdout.txt file found 2024-07-18 11:55:00,974 DEBUG --- stderr --- 2024-07-18 11:55:00,974 DEBUG 2024-07-18 11:55:00,974 INFO 2024-07-18 11:55:00,974 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:00,974 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:01,300 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:01,300 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:01,300 DEBUG --- stdout --- 2024-07-18 11:55:01,301 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:01,301 DEBUG --- stderr --- 2024-07-18 11:55:01,301 DEBUG 2024-07-18 11:55:01,301 INFO 2024-07-18 11:55:01,301 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-110/stdout.txt 2024-07-18 11:55:02,106 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 11:55:02,106 DEBUG --- stdout --- 2024-07-18 11:55:02,107 DEBUG 3368 /results/orders/benchmark-110/stdout.txt 2024-07-18 11:55:02,107 DEBUG --- stderr --- 2024-07-18 11:55:02,107 DEBUG 2024-07-18 11:55:02,107 INFO - Download file (size 3368k) 2024-07-18 11:55:02,107 INFO 2024-07-18 11:55:02,107 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:02,107 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:02,425 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:02,425 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:02,425 DEBUG --- stdout --- 2024-07-18 11:55:02,425 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:02,425 DEBUG --- stderr --- 2024-07-18 11:55:02,425 DEBUG source : /results/orders/benchmark-110/stdout.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/stdout.txt 2024-07-18 11:55:02,425 INFO 2024-07-18 11:55:02,425 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:02,426 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:02,741 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:02,741 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:02,741 DEBUG --- stdout --- 2024-07-18 11:55:02,741 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:02,741 DEBUG --- stderr --- 2024-07-18 11:55:02,741 DEBUG 2024-07-18 11:55:02,742 INFO 2024-07-18 11:55:02,742 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-110/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/stdout.txt 2024-07-18 11:55:02,742 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 11:55:03,984 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:03,984 DEBUG --- stdout --- 2024-07-18 11:55:03,984 DEBUG tar: Removing leading `/' from member names 2024-07-18 11:55:03,984 DEBUG --- stderr --- 2024-07-18 11:55:03,984 DEBUG ________________________________________________________________________________ [2024-07-18 11:55:03] benchmark-110 show_cmd : Display order command ________________________________________________________________________________ 2024-07-18 11:55:03,985 INFO 2024-07-18 11:55:03,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 11:55:03,985 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:04,299 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:04,299 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:04,299 DEBUG --- stdout --- 2024-07-18 11:55:04,299 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:04,299 DEBUG --- stderr --- 2024-07-18 11:55:04,299 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-18 11:55:04,300 INFO 2024-07-18 11:55:04,300 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:04,300 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:04,626 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:04,627 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:04,627 DEBUG --- stdout --- 2024-07-18 11:55:04,627 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:04,627 DEBUG --- stderr --- 2024-07-18 11:55:04,627 DEBUG 2024-07-18 11:55:04,627 INFO 2024-07-18 11:55:04,627 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- bash -c 'if [ -f "/results/orders/benchmark-110/cmd.txt" ]; then echo "/results/orders/benchmark-110/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-110/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-110/cmd.txt file found" 2024-07-18 11:55:04,627 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-18 11:55:05,449 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-18 11:55:05,449 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:05,449 DEBUG --- stdout --- 2024-07-18 11:55:05,450 DEBUG /results/orders/benchmark-110/cmd.txt file found 2024-07-18 11:55:05,450 DEBUG --- stderr --- 2024-07-18 11:55:05,450 DEBUG 2024-07-18 11:55:05,450 INFO 2024-07-18 11:55:05,450 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-07-18 11:55:05,450 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:05,766 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:05,767 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:05,767 DEBUG --- stdout --- 2024-07-18 11:55:05,767 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:05,767 DEBUG --- stderr --- 2024-07-18 11:55:05,767 DEBUG 2024-07-18 11:55:05,767 INFO 2024-07-18 11:55:05,767 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-110/cmd.txt 2024-07-18 11:55:06,590 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-18 11:55:06,590 DEBUG --- stdout --- 2024-07-18 11:55:06,591 DEBUG 4 /results/orders/benchmark-110/cmd.txt 2024-07-18 11:55:06,591 DEBUG --- stderr --- 2024-07-18 11:55:06,591 DEBUG 2024-07-18 11:55:06,591 INFO - Download file (size 4k) 2024-07-18 11:55:06,591 INFO 2024-07-18 11:55:06,591 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:06,591 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:06,919 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:06,919 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:06,919 DEBUG --- stdout --- 2024-07-18 11:55:06,920 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:06,920 DEBUG --- stderr --- 2024-07-18 11:55:06,920 DEBUG source : /results/orders/benchmark-110/cmd.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/cmd.txt 2024-07-18 11:55:06,920 INFO 2024-07-18 11:55:06,920 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:06,920 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-18 11:55:07,237 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-18 11:55:07,237 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:07,237 DEBUG --- stdout --- 2024-07-18 11:55:07,238 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-18 11:55:07,238 DEBUG --- stderr --- 2024-07-18 11:55:07,238 DEBUG 2024-07-18 11:55:07,238 INFO 2024-07-18 11:55:07,238 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/benchmark-110/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/cmd.txt 2024-07-18 11:55:07,238 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-18 11:55:08,031 INFO [loop_until]: OK (rc = 0) 2024-07-18 11:55:08,032 DEBUG --- stdout --- 2024-07-18 11:55:08,032 DEBUG tar: Removing leading `/' from member names 2024-07-18 11:55:08,032 DEBUG --- stderr --- 2024-07-18 11:55:08,032 DEBUG 2024-07-18 11:55:08,033 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/cmd.txt is small (1 lines), printing all lines: 2024-07-18 11:55:08,033 DEBUG ----- output ----- 2024-07-18 11:55:08,033 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-110 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-07-18 11:55:08,033 INFO ________________________________________________________________________________ [2024-07-18 11:55:08] benchmark-110 show_rc : Display rc order ________________________________________________________________________________ 2024-07-18 11:55:08,034 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/rc.txt is small (1 lines), printing all lines: 2024-07-18 11:55:08,034 DEBUG ----- output ----- 2024-07-18 11:55:08,034 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-07-18 11:55:08,034 INFO ________________________________________________________________________________ [2024-07-18 11:55:08] benchmark-110 show_stdout : Display stdout order ________________________________________________________________________________ 2024-07-18 11:55:08,050 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/06_benchmark-110/stdout.txt is 44959 lines): 2024-07-18 11:55:08,050 DEBUG ----- output ----- 2024-07-18 11:55:08,050 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-07-18 11:55:08,050 INFO Gatling 3.11.5 is available! (you're using 3.9.5) 2024-07-18 11:55:08,050 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-07-18 11:55:08,051 INFO 11:52:41.495 [ERROR] i.g.h.a.HttpRequestAction - 'tokenUserinfo' failed to execute: No attribute named 'access_token' is defined 2024-07-18 11:55:08,051 INFO 2024-07-18 11:55:08,051 INFO ================================================================================ 2024-07-18 11:55:08,051 INFO 2024-07-18 11:52:42 5s elapsed 2024-07-18 11:55:08,051 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 11:55:08,051 INFO > Global (OK=275 KO=0 ) 2024-07-18 11:55:08,051 INFO > registration_restLoginInitiate (OK=4 KO=0 ) 2024-07-18 11:55:08,051 INFO > registrationCallback (OK=4 KO=0 ) 2024-07-18 11:55:08,051 INFO > restLoginInitiate (OK=41 KO=0 ) 2024-07-18 11:55:08,051 INFO > restLoginUsernamePasswordCallback (OK=40 KO=0 ) 2024-07-18 11:55:08,051 INFO > Skip 2FA (OK=31 KO=0 ) 2024-07-18 11:55:08,051 INFO > User Authorize PKCE stage (OK=37 KO=0 ) 2024-07-18 11:55:08,051 INFO > User AccessToken PKCE stage (OK=36 KO=0 ) 2024-07-18 11:55:08,051 INFO > RootUserinfo (OK=41 KO=0 ) 2024-07-18 11:55:08,051 INFO > getIdToken (OK=7 KO=0 ) 2024-07-18 11:55:08,051 INFO > getIdToken Redirect 1 (OK=7 KO=0 ) 2024-07-18 11:55:08,051 INFO > createOrganization (OK=7 KO=0 ) 2024-07-18 11:55:08,051 INFO > queryOrganization (OK=7 KO=0 ) 2024-07-18 11:55:08,051 INFO > deleteOrganization (OK=7 KO=0 ) 2024-07-18 11:55:08,051 INFO > tokenUserinfo (OK=6 KO=0 ) 2024-07-18 11:55:08,051 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 11:55:08,051 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 1 (100.0%) 2024-07-18 11:55:08,051 INFO cess_token' is defined 2024-07-18 11:55:08,051 INFO 2024-07-18 11:55:08,051 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 11:55:08,051 INFO [- ] 0% 2024-07-18 11:55:08,051 INFO waiting: 397489 / active: 28 / done: 18 2024-07-18 11:55:08,051 INFO ================================================================================ 2024-07-18 11:55:08,051 INFO 2024-07-18 11:55:08,051 INFO 11:52:45.951 [ERROR] i.g.h.a.HttpRequestAction - 'tokenUserinfo' failed to execute: No attribute named 'access_token' is defined 2024-07-18 11:55:08,051 INFO 2024-07-18 11:55:08,051 INFO ================================================================================ 2024-07-18 11:55:08,051 INFO 2024-07-18 11:52:47 10s elapsed 2024-07-18 11:55:08,051 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 11:55:08,051 INFO > Global (OK=1353 KO=0 ) 2024-07-18 11:55:08,051 INFO > registration_restLoginInitiate (OK=27 KO=0 ) 2024-07-18 11:55:08,051 INFO > registrationCallback (OK=25 KO=0 ) 2024-07-18 11:55:08,051 INFO > restLoginInitiate (OK=155 KO=0 ) 2024-07-18 11:55:08,051 INFO > restLoginUsernamePasswordCallback (OK=153 KO=0 ) 2024-07-18 11:55:08,052 INFO > Skip 2FA (OK=117 KO=0 ) 2024-07-18 11:55:08,052 INFO > User Authorize PKCE stage (OK=154 KO=0 ) 2024-07-18 11:55:08,052 INFO > User AccessToken PKCE stage (OK=152 KO=0 ) 2024-07-18 11:55:08,052 INFO > RootUserinfo (OK=296 KO=0 ) 2024-07-18 11:55:08,052 INFO > getIdToken (OK=25 KO=0 ) 2024-07-18 11:55:08,052 INFO > getIdToken Redirect 1 (OK=25 KO=0 ) 2024-07-18 11:55:08,052 INFO > createOrganization (OK=25 KO=0 ) 2024-07-18 11:55:08,052 INFO > queryOrganization (OK=25 KO=0 ) 2024-07-18 11:55:08,052 INFO > deleteOrganization (OK=25 KO=0 ) 2024-07-18 11:55:08,052 INFO > tokenUserinfo (OK=26 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI-Post-Login (OK=6 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI-Post-Login Redirect 1 (OK=18 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI AM idFromSession (OK=6 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI AM validateGoto (OK=6 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI Enduser appAuthHelper2 (OK=6 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI Enduser appAuthHelper1 (OK=6 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI Enduser sessioncheck (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI IDM uiconfig (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI IDM version (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI IDM privilege (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI IDM login (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI IDM features (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI IDM dashboard (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI IDM user (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI IDM alpha_user (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI IDM oidcToken (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > userinfo (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI-Logout Redirect 1 (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI AM revoke1 (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI AM revoke2 (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO > UI AM endsession (OK=5 KO=0 ) 2024-07-18 11:55:08,052 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 11:55:08,052 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 2 (100.0%) 2024-07-18 11:55:08,052 INFO cess_token' is defined 2024-07-18 11:55:08,052 INFO 2024-07-18 11:55:08,052 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 11:55:08,052 INFO [- ] 0% 2024-07-18 11:55:08,052 INFO waiting: 397351 / active: 80 / done: 104 2024-07-18 11:55:08,052 INFO ================================================================================ 2024-07-18 11:55:08,052 INFO 2024-07-18 11:55:08,052 INFO 11:52:47.532 [ERROR] i.g.h.a.HttpRequestAction - 'tokenUserinfo' failed to execute: No attribute named 'access_token' is defined 2024-07-18 11:55:08,053 INFO 2024-07-18 11:55:08,053 INFO ================================================================================ 2024-07-18 11:55:08,053 INFO 2024-07-18 11:52:52 15s elapsed 2024-07-18 11:55:08,053 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 11:55:08,053 INFO > Global (OK=3098 KO=0 ) 2024-07-18 11:55:08,053 INFO > registration_restLoginInitiate (OK=63 KO=0 ) 2024-07-18 11:55:08,053 INFO > registrationCallback (OK=61 KO=0 ) 2024-07-18 11:55:08,053 INFO > restLoginInitiate (OK=348 KO=0 ) 2024-07-18 11:55:08,053 INFO > restLoginUsernamePasswordCallback (OK=344 KO=0 ) 2024-07-18 11:55:08,053 INFO > Skip 2FA (OK=266 KO=0 ) 2024-07-18 11:55:08,053 INFO > User Authorize PKCE stage (OK=346 KO=0 ) 2024-07-18 11:55:08,053 INFO > User AccessToken PKCE stage (OK=343 KO=0 ) 2024-07-18 11:55:08,053 INFO > RootUserinfo (OK=795 KO=0 ) 2024-07-18 11:55:08,053 INFO > getIdToken (OK=49 KO=0 ) 2024-07-18 11:55:08,053 INFO > getIdToken Redirect 1 (OK=49 KO=0 ) 2024-07-18 11:55:08,053 INFO > createOrganization (OK=48 KO=0 ) 2024-07-18 11:55:08,053 INFO > queryOrganization (OK=48 KO=0 ) 2024-07-18 11:55:08,053 INFO [...] 2024-07-18 11:55:08,065 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 11:55:08,065 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 2236 (97.90%) 2024-07-18 11:55:08,066 INFO cess_token' is defined 2024-07-18 11:55:08,066 INFO > RootUserinfo: Failed to build request: No attribute named 'acc 30 ( 1.31%) 2024-07-18 11:55:08,066 INFO ess_token' is defined 2024-07-18 11:55:08,066 INFO > status.find.is(302), but actually found 502 6 ( 0.26%) 2024-07-18 11:55:08,066 INFO > status.find.is(200), but actually found 502 5 ( 0.22%) 2024-07-18 11:55:08,066 INFO > createOrganization: Failed to build request: No attribute name 2 ( 0.09%) 2024-07-18 11:55:08,066 INFO d 'access_token' is defined 2024-07-18 11:55:08,066 INFO > queryOrganization: Failed to build request: No attribute named 2 ( 0.09%) 2024-07-18 11:55:08,066 INFO 'organization_id' is defined 2024-07-18 11:55:08,066 INFO > deleteOrganization: Failed to build request: No attribute name 2 ( 0.09%) 2024-07-18 11:55:08,066 INFO d 'organization_id' is defined 2024-07-18 11:55:08,066 INFO > status.find.in([200, 209], 304), found 502 1 ( 0.04%) 2024-07-18 11:55:08,066 INFO 2024-07-18 11:55:08,066 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 11:55:08,066 INFO [#########################################################################-] 99% 2024-07-18 11:55:08,066 INFO waiting: 0 / active: 45 / done: 397490 2024-07-18 11:55:08,066 INFO ================================================================================ 2024-07-18 11:55:08,066 INFO 2024-07-18 11:55:08,066 INFO 2024-07-18 11:55:08,066 INFO ================================================================================ 2024-07-18 11:55:08,066 INFO 2024-07-18 12:53:16 3639s elapsed 2024-07-18 11:55:08,066 INFO ---- Requests ------------------------------------------------------------------ 2024-07-18 11:55:08,066 INFO > Global (OK=3364112 KO=12 ) 2024-07-18 11:55:08,066 INFO > registration_restLoginInitiate (OK=63471 KO=0 ) 2024-07-18 11:55:08,066 INFO > registrationCallback (OK=63471 KO=0 ) 2024-07-18 11:55:08,066 INFO > restLoginInitiate (OK=334064 KO=0 ) 2024-07-18 11:55:08,066 INFO > restLoginUsernamePasswordCallback (OK=334061 KO=3 ) 2024-07-18 11:55:08,066 INFO > Skip 2FA (OK=238217 KO=2 ) 2024-07-18 11:55:08,066 INFO > User Authorize PKCE stage (OK=347738 KO=6 ) 2024-07-18 11:55:08,066 INFO > User AccessToken PKCE stage (OK=347737 KO=1 ) 2024-07-18 11:55:08,066 INFO > RootUserinfo (OK=953100 KO=0 ) 2024-07-18 11:55:08,066 INFO > getIdToken (OK=47593 KO=0 ) 2024-07-18 11:55:08,066 INFO > getIdToken Redirect 1 (OK=47593 KO=0 ) 2024-07-18 11:55:08,067 INFO > createOrganization (OK=47591 KO=0 ) 2024-07-18 11:55:08,067 INFO > queryOrganization (OK=47591 KO=0 ) 2024-07-18 11:55:08,067 INFO > deleteOrganization (OK=47591 KO=0 ) 2024-07-18 11:55:08,067 INFO > tokenUserinfo (OK=77686 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI-Post-Login (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI-Post-Login Redirect 1 (OK=47760 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI AM idFromSession (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI AM validateGoto (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI Enduser appAuthHelper2 (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI Enduser appAuthHelper1 (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI Enduser sessioncheck (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI IDM uiconfig (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI IDM version (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI IDM privilege (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI IDM login (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI IDM features (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI IDM dashboard (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI IDM user (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI IDM alpha_user (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI IDM oidcToken (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > userinfo (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI-Logout Redirect 1 (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI AM revoke1 (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI AM revoke2 (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > UI AM endsession (OK=15920 KO=0 ) 2024-07-18 11:55:08,067 INFO > restloginProgressiveProfileCallback (OK=448 KO=0 ) 2024-07-18 11:55:08,067 INFO ---- Errors -------------------------------------------------------------------- 2024-07-18 11:55:08,067 INFO > tokenUserinfo: Failed to build request: No attribute named 'ac 2236 (97.90%) 2024-07-18 11:55:08,067 INFO cess_token' is defined 2024-07-18 11:55:08,067 INFO > RootUserinfo: Failed to build request: No attribute named 'acc 30 ( 1.31%) 2024-07-18 11:55:08,067 INFO ess_token' is defined 2024-07-18 11:55:08,067 INFO > status.find.is(302), but actually found 502 6 ( 0.26%) 2024-07-18 11:55:08,067 INFO > status.find.is(200), but actually found 502 5 ( 0.22%) 2024-07-18 11:55:08,067 INFO > createOrganization: Failed to build request: No attribute name 2 ( 0.09%) 2024-07-18 11:55:08,067 INFO d 'access_token' is defined 2024-07-18 11:55:08,067 INFO > queryOrganization: Failed to build request: No attribute named 2 ( 0.09%) 2024-07-18 11:55:08,067 INFO 'organization_id' is defined 2024-07-18 11:55:08,067 INFO > deleteOrganization: Failed to build request: No attribute name 2 ( 0.09%) 2024-07-18 11:55:08,067 INFO d 'organization_id' is defined 2024-07-18 11:55:08,067 INFO > status.find.in([200, 209], 304), found 502 1 ( 0.04%) 2024-07-18 11:55:08,067 INFO 2024-07-18 11:55:08,068 INFO ---- idc.benchmark.CH2023 ------------------------------------------------------ 2024-07-18 11:55:08,068 INFO [##########################################################################]100% 2024-07-18 11:55:08,068 INFO waiting: 0 / active: 0 / done: 397535 2024-07-18 11:55:08,068 INFO ================================================================================ 2024-07-18 11:55:08,068 INFO 2024-07-18 11:55:08,068 INFO Simulation com.forgerock.gatling.Loadtest completed in 3639 seconds 2024-07-18 11:55:08,068 INFO 2024-07-18 11:55:08,068 INFO ---------------------- 2024-07-18 11:55:08,068 INFO [18/Jul/2024 12:53:16] Create tar file benchmark-110.tar.gz 2024-07-18 11:55:08,068 INFO ---------------------- 2024-07-18 11:55:08,068 INFO RESULTS_FOLDER=/results/gatling/ 2024-07-18 11:55:08,068 INFO RESULT_FOLDER=benchmark-110 2024-07-18 11:55:08,068 INFO SIMULATION_PATH=benchmark-110/loadtest-20240718105236505 2024-07-18 11:55:08,068 INFO PWD=/results/gatling/benchmark-110/loadtest-20240718105236505 2024-07-18 11:55:08,068 INFO [cmd] tar -czvf /results/gatling//benchmark-110.tar.gz --exclude=simulation.log * 2024-07-18 11:55:08,068 INFO /results/gatling 2024-07-18 11:55:08,068 INFO 2024-07-18 11:55:08,068 INFO ---------------------- 2024-07-18 11:55:08,068 INFO [18/Jul/2024 12:53:16] Update /results/gatling//latest 2024-07-18 11:55:08,068 INFO ---------------------- 2024-07-18 11:55:08,068 INFO 2024-07-18 11:55:08,068 INFO ---------------------- 2024-07-18 11:55:08,068 INFO [18/Jul/2024 12:53:16] Update /results/gatling//latest.tar.gz 2024-07-18 11:55:08,068 INFO ---------------------- 2024-07-18 11:55:08,068 INFO ________________________________________________________________________________ [2024-07-18 11:55:08] benchmark-110 show_stderr : Display stderr order ________________________________________________________________________________ 2024-07-18 11:55:08,069 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/07_Scenario_benchmark/06_benchmark-110/stderr.txt is small (0 lines), printing all lines: 2024-07-18 11:55:08,069 DEBUG ----- output ----- 2024-07-18 11:55:08,069 DEBUG 2024-07-18 11:55:08,069 INFO ________________________________________________________________________________ [2024-07-18 11:55:08] benchmark-110 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 11:55:08] benchmark-110 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-07-18 11:55:08] benchmark-110 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped