--Task-- name: benchmark-80 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 below loop: False interval: None dependencies: [] wait_for: ['benchmark-40'] preceding_task: benchmark-40 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 80} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-18 18:18:55] benchmark-80 pre : N/A ________________________________________________________________________________ 2024-11-18 18:18:55 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-18 18:18:55 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-80/stdout.txt Pod log : /results/orders/benchmark-80 2024-11-18 18:18:55,130 INFO 2024-11-18 18:18:55,130 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-11-18 18:18:55,130 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:55,403 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:55,403 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:55,403 DEBUG --- stdout --- 2024-11-18 18:18:55,403 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:55,403 DEBUG --- stderr --- 2024-11-18 18:18:55,403 DEBUG ________________________________________________________________________________ [2024-11-18 18:18:55] benchmark-80 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-18 18:18:55,403 INFO 2024-11-18 18:18:55,403 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-11-18 18:18:55,403 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:55,676 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:55,676 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:55,676 DEBUG --- stdout --- 2024-11-18 18:18:55,676 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:55,676 DEBUG --- stderr --- 2024-11-18 18:18:55,676 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-80" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=200000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=80 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-18 18:18:55] benchmark-80 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-18 18:18:55,676 INFO 2024-11-18 18:18:55,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-11-18 18:18:55,676 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:55,951 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:55,951 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:55,951 DEBUG --- stdout --- 2024-11-18 18:18:55,951 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:55,951 DEBUG --- stderr --- 2024-11-18 18:18:55,951 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/order.json { "name": "benchmark-80", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-80\" --no-reports --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx4G\" --simulation-system-properties=\"-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=200000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=80 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-80" } ________________________________________________________________________________ [2024-11-18 18:18:55] benchmark-80 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-11-18 18:18:55,955 INFO 2024-11-18 18:18:55,955 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-18 18:18:55,955 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 18:18:56,167 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 18:18:56,167 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:56,167 DEBUG --- stdout --- 2024-11-18 18:18:56,167 DEBUG WAITING 2024-11-18 18:18:56,167 DEBUG --- stderr --- 2024-11-18 18:18:56,167 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 34 0 --:--:-- --:--:-- --:--:-- 34 ________________________________________________________________________________ [2024-11-18 18:18:56] benchmark-80 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-18 18:18:56,168 INFO 2024-11-18 18:18:56,168 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-11-18 18:18:56,168 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:56,439 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:56,439 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:56,439 DEBUG --- stdout --- 2024-11-18 18:18:56,440 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:56,440 DEBUG --- stderr --- 2024-11-18 18:18:56,440 DEBUG 2024-11-18 18:18:56,440 INFO 2024-11-18 18:18:56,440 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/order.json overseer-0-6644bb6697-rchxr:/results/orders/order.json 2024-11-18 18:18:56,440 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 18:18:57,779 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:57,779 DEBUG --- stdout --- 2024-11-18 18:18:57,779 DEBUG 2024-11-18 18:18:57,779 DEBUG --- stderr --- 2024-11-18 18:18:57,779 DEBUG Order has been successfully forwarded to overseer 2024-11-18 18:18:57 - INFO: Check if Gatling simulation started successfully 2024-11-18 18:18:57,779 INFO 2024-11-18 18:18:57,779 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-11-18 18:18:57,779 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:58,053 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:58,053 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:58,053 DEBUG --- stdout --- 2024-11-18 18:18:58,053 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:58,053 DEBUG --- stderr --- 2024-11-18 18:18:58,053 DEBUG 2024-11-18 18:18:58,054 INFO 2024-11-18 18:18:58,054 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr -- head --lines 5000 /results/orders/benchmark-80/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-18 18:18:58,055 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 18:18:58,772 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 18:19:04,491 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 18:19:10,261 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-18 18:19:10,262 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:19:10,262 DEBUG --- stdout --- 2024-11-18 18:19:10,262 DEBUG 2024-11-18 18:18:58,530 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpvyb7m1c7 2024-11-18 18:18:58,532 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-80" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=200000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=80 -Duser_password=**** [INFO] Scanning for projects... [INFO] [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- [INFO] Building pyrock-gatling-simulation 3.12.0 [INFO] from pom.xml [INFO] --------------------------------[ jar ]--------------------------------- [INFO] [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> [INFO] [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- [INFO] skip non existing resourceDirectory /tmp/tmpvyb7m1c7/src/main/resources [INFO] [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- [INFO] No sources to compile [INFO] [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- [INFO] Copying 2 resources from src/test/resources to target/test-classes [INFO] [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- [INFO] Nothing to compile - all classes are up to date. [INFO] [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- [INFO] compiling 38 Scala sources to /tmp/tmpvyb7m1c7/target/test-classes ... [INFO] compile in 6.8 s [INFO] [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< [INFO] [INFO] [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- [INFO] Running simulation com.forgerock.pyrock.Loadtest. Gatling 3.13.1 is available! (you're using 3.12.0) System property think_time_multiplier not set. No think time emulation will be done in the simulation. Simulation com.forgerock.pyrock.Loadtest started... 2024-11-18 18:19:10,262 DEBUG --- stderr --- 2024-11-18 18:19:10,262 DEBUG ________________________________________________________________________________ [2024-11-18 18:19:10] benchmark-80 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m. Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 03s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 04s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 05s, retry Order is running since 40m 05s, retry Order is running since 42m 06s, retry Order is running since 44m 06s, retry Order is running since 46m 06s, retry Order is running since 48m 07s, retry Order is running since 50m 07s, retry Order is running since 52m 07s, retry Order is running since 54m 07s, retry Order is running since 56m 08s, retry Order is running since 58m 08s, retry PASS : Order stopped by itself after 1h and before timeout (1h 12m) ________________________________________________________________________________ [2024-11-18 19:19:19] benchmark-80 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-18 19:19:19,123 INFO 2024-11-18 19:19:19,123 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-11-18 19:19:19,123 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:19,415 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:19,415 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:19,415 DEBUG --- stdout --- 2024-11-18 19:19:19,415 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:19,415 DEBUG --- stderr --- 2024-11-18 19:19:19,415 DEBUG 2024-11-18 19:19:19,415 INFO 2024-11-18 19:19:19,415 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr -- rm -f /results/orders/order.json 2024-11-18 19:19:19,415 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 19:19:20,180 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:20,180 DEBUG --- stdout --- 2024-11-18 19:19:20,180 DEBUG 2024-11-18 19:19:20,180 DEBUG --- stderr --- 2024-11-18 19:19:20,180 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-18 19:19:20] benchmark-80 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-18 19:19:20 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/order.json 2024-11-18 19:19:20,181 INFO 2024-11-18 19:19:20,181 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-11-18 19:19:20,181 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:20,453 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:20,454 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:20,454 DEBUG --- stdout --- 2024-11-18 19:19:20,454 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:20,454 DEBUG --- stderr --- 2024-11-18 19:19:20,454 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 19:19:20,454 INFO 2024-11-18 19:19:20,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-11-18 19:19:20,454 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:20,730 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:20,730 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:20,730 DEBUG --- stdout --- 2024-11-18 19:19:20,730 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:20,730 DEBUG --- stderr --- 2024-11-18 19:19:20,730 DEBUG 2024-11-18 19:19:20,731 INFO 2024-11-18 19:19:20,731 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- bash -c 'if [ -f "/results/orders/benchmark-80/rc.txt" ]; then echo "/results/orders/benchmark-80/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-80/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-80/rc.txt file found" 2024-11-18 19:19:20,731 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 19:19:21,508 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 19:19:21,508 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:21,508 DEBUG --- stdout --- 2024-11-18 19:19:21,509 DEBUG /results/orders/benchmark-80/rc.txt file found 2024-11-18 19:19:21,509 DEBUG --- stderr --- 2024-11-18 19:19:21,509 DEBUG 2024-11-18 19:19:21,509 INFO 2024-11-18 19:19:21,509 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-11-18 19:19:21,509 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:21,781 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:21,782 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:21,782 DEBUG --- stdout --- 2024-11-18 19:19:21,782 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:21,782 DEBUG --- stderr --- 2024-11-18 19:19:21,782 DEBUG 2024-11-18 19:19:21,782 INFO 2024-11-18 19:19:21,782 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- du -k /results/orders/benchmark-80/rc.txt 2024-11-18 19:19:22,561 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 19:19:22,561 DEBUG --- stdout --- 2024-11-18 19:19:22,561 DEBUG 4 /results/orders/benchmark-80/rc.txt 2024-11-18 19:19:22,561 DEBUG --- stderr --- 2024-11-18 19:19:22,561 DEBUG 2024-11-18 19:19:22,561 INFO - Download file (size 4k) 2024-11-18 19:19:22,562 INFO 2024-11-18 19:19:22,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-11-18 19:19:22,562 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:22,856 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:22,856 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:22,856 DEBUG --- stdout --- 2024-11-18 19:19:22,857 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:22,857 DEBUG --- stderr --- 2024-11-18 19:19:22,857 DEBUG source : /results/orders/benchmark-80/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/rc.txt 2024-11-18 19:19:22,857 INFO 2024-11-18 19:19:22,857 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-11-18 19:19:22,857 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:23,130 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:23,130 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:23,130 DEBUG --- stdout --- 2024-11-18 19:19:23,130 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:23,130 DEBUG --- stderr --- 2024-11-18 19:19:23,130 DEBUG 2024-11-18 19:19:23,131 INFO 2024-11-18 19:19:23,131 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-80/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/rc.txt 2024-11-18 19:19:23,131 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 19:19:23,883 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:23,884 DEBUG --- stdout --- 2024-11-18 19:19:23,884 DEBUG tar: Removing leading `/' from member names 2024-11-18 19:19:23,884 DEBUG --- stderr --- 2024-11-18 19:19:23,884 DEBUG 2024-11-18 19:19:23,884 INFO 2024-11-18 19:19:23,884 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-11-18 19:19:23,884 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:24,157 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:24,157 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:24,157 DEBUG --- stdout --- 2024-11-18 19:19:24,157 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:24,157 DEBUG --- stderr --- 2024-11-18 19:19:24,157 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 19:19:24,158 INFO 2024-11-18 19:19:24,158 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-11-18 19:19:24,158 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:24,432 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:24,432 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:24,432 DEBUG --- stdout --- 2024-11-18 19:19:24,432 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:24,432 DEBUG --- stderr --- 2024-11-18 19:19:24,433 DEBUG 2024-11-18 19:19:24,433 INFO 2024-11-18 19:19:24,433 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- bash -c 'if [ -f "/results/orders/benchmark-80/stderr.txt" ]; then echo "/results/orders/benchmark-80/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-80/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-80/stderr.txt file found" 2024-11-18 19:19:24,433 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 19:19:25,223 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 19:19:25,224 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:25,224 DEBUG --- stdout --- 2024-11-18 19:19:25,224 DEBUG /results/orders/benchmark-80/stderr.txt file found 2024-11-18 19:19:25,224 DEBUG --- stderr --- 2024-11-18 19:19:25,224 DEBUG 2024-11-18 19:19:25,224 INFO 2024-11-18 19:19:25,224 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-11-18 19:19:25,224 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:25,501 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:25,501 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:25,501 DEBUG --- stdout --- 2024-11-18 19:19:25,501 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:25,501 DEBUG --- stderr --- 2024-11-18 19:19:25,501 DEBUG 2024-11-18 19:19:25,501 INFO 2024-11-18 19:19:25,501 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- du -k /results/orders/benchmark-80/stderr.txt 2024-11-18 19:19:26,279 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 19:19:26,279 DEBUG --- stdout --- 2024-11-18 19:19:26,279 DEBUG 0 /results/orders/benchmark-80/stderr.txt 2024-11-18 19:19:26,279 DEBUG --- stderr --- 2024-11-18 19:19:26,279 DEBUG 2024-11-18 19:19:26,280 INFO - Download file (size 0k) 2024-11-18 19:19:26,280 INFO 2024-11-18 19:19:26,280 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-11-18 19:19:26,280 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:26,554 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:26,554 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:26,554 DEBUG --- stdout --- 2024-11-18 19:19:26,554 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:26,554 DEBUG --- stderr --- 2024-11-18 19:19:26,554 DEBUG source : /results/orders/benchmark-80/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stderr.txt 2024-11-18 19:19:26,554 INFO 2024-11-18 19:19:26,554 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-11-18 19:19:26,554 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:26,825 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:26,825 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:26,825 DEBUG --- stdout --- 2024-11-18 19:19:26,826 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:26,826 DEBUG --- stderr --- 2024-11-18 19:19:26,826 DEBUG 2024-11-18 19:19:26,826 INFO 2024-11-18 19:19:26,826 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-80/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stderr.txt 2024-11-18 19:19:26,826 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 19:19:27,574 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:27,574 DEBUG --- stdout --- 2024-11-18 19:19:27,574 DEBUG tar: Removing leading `/' from member names 2024-11-18 19:19:27,574 DEBUG --- stderr --- 2024-11-18 19:19:27,574 DEBUG 2024-11-18 19:19:27,575 INFO 2024-11-18 19:19:27,575 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-11-18 19:19:27,575 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:27,846 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:27,846 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:27,847 DEBUG --- stdout --- 2024-11-18 19:19:27,847 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:27,847 DEBUG --- stderr --- 2024-11-18 19:19:27,847 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 19:19:27,847 INFO 2024-11-18 19:19:27,847 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-11-18 19:19:27,847 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:28,119 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:28,119 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:28,119 DEBUG --- stdout --- 2024-11-18 19:19:28,119 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:28,119 DEBUG --- stderr --- 2024-11-18 19:19:28,119 DEBUG 2024-11-18 19:19:28,120 INFO 2024-11-18 19:19:28,120 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- bash -c 'if [ -f "/results/orders/benchmark-80/stdout.txt" ]; then echo "/results/orders/benchmark-80/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-80/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-80/stdout.txt file found" 2024-11-18 19:19:28,120 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 19:19:28,903 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 19:19:28,903 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:28,903 DEBUG --- stdout --- 2024-11-18 19:19:28,903 DEBUG /results/orders/benchmark-80/stdout.txt file found 2024-11-18 19:19:28,903 DEBUG --- stderr --- 2024-11-18 19:19:28,903 DEBUG 2024-11-18 19:19:28,903 INFO 2024-11-18 19:19:28,903 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 19:19:28,903 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:29,176 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:29,176 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:29,176 DEBUG --- stdout --- 2024-11-18 19:19:29,176 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:29,176 DEBUG --- stderr --- 2024-11-18 19:19:29,177 DEBUG 2024-11-18 19:19:29,177 INFO 2024-11-18 19:19:29,177 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- du -k /results/orders/benchmark-80/stdout.txt 2024-11-18 19:19:30,026 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 19:19:30,027 DEBUG --- stdout --- 2024-11-18 19:19:30,027 DEBUG 5056 /results/orders/benchmark-80/stdout.txt 2024-11-18 19:19:30,027 DEBUG --- stderr --- 2024-11-18 19:19:30,027 DEBUG 2024-11-18 19:19:30,027 INFO - Download file (size 5056k) 2024-11-18 19:19:30,027 INFO 2024-11-18 19:19:30,027 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-11-18 19:19:30,027 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:30,325 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:30,325 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:30,325 DEBUG --- stdout --- 2024-11-18 19:19:30,326 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:30,326 DEBUG --- stderr --- 2024-11-18 19:19:30,326 DEBUG source : /results/orders/benchmark-80/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stdout.txt 2024-11-18 19:19:30,326 INFO 2024-11-18 19:19:30,326 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-11-18 19:19:30,326 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:30,597 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:30,597 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:30,597 DEBUG --- stdout --- 2024-11-18 19:19:30,597 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:30,597 DEBUG --- stderr --- 2024-11-18 19:19:30,597 DEBUG 2024-11-18 19:19:30,598 INFO 2024-11-18 19:19:30,598 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-80/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stdout.txt 2024-11-18 19:19:30,598 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 19:19:31,843 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:31,843 DEBUG --- stdout --- 2024-11-18 19:19:31,843 DEBUG tar: Removing leading `/' from member names 2024-11-18 19:19:31,844 DEBUG --- stderr --- 2024-11-18 19:19:31,844 DEBUG ________________________________________________________________________________ [2024-11-18 19:19:31] benchmark-80 show_cmd : Display order command ________________________________________________________________________________ 2024-11-18 19:19:31,844 INFO 2024-11-18 19:19:31,844 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-11-18 19:19:31,844 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:32,118 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:32,118 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:32,118 DEBUG --- stdout --- 2024-11-18 19:19:32,118 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:32,118 DEBUG --- stderr --- 2024-11-18 19:19:32,118 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 19:19:32,118 INFO 2024-11-18 19:19:32,118 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-11-18 19:19:32,118 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:32,391 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:32,391 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:32,391 DEBUG --- stdout --- 2024-11-18 19:19:32,391 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:32,391 DEBUG --- stderr --- 2024-11-18 19:19:32,391 DEBUG 2024-11-18 19:19:32,393 INFO 2024-11-18 19:19:32,393 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- bash -c 'if [ -f "/results/orders/benchmark-80/cmd.txt" ]; then echo "/results/orders/benchmark-80/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-80/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-80/cmd.txt file found" 2024-11-18 19:19:32,393 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 19:19:33,211 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 19:19:33,211 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:33,211 DEBUG --- stdout --- 2024-11-18 19:19:33,211 DEBUG /results/orders/benchmark-80/cmd.txt file found 2024-11-18 19:19:33,211 DEBUG --- stderr --- 2024-11-18 19:19:33,211 DEBUG 2024-11-18 19:19:33,211 INFO 2024-11-18 19:19:33,211 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-11-18 19:19:33,211 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:33,482 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:33,482 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:33,482 DEBUG --- stdout --- 2024-11-18 19:19:33,482 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:33,482 DEBUG --- stderr --- 2024-11-18 19:19:33,482 DEBUG 2024-11-18 19:19:33,482 INFO 2024-11-18 19:19:33,482 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- du -k /results/orders/benchmark-80/cmd.txt 2024-11-18 19:19:34,255 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 19:19:34,255 DEBUG --- stdout --- 2024-11-18 19:19:34,255 DEBUG 4 /results/orders/benchmark-80/cmd.txt 2024-11-18 19:19:34,255 DEBUG --- stderr --- 2024-11-18 19:19:34,255 DEBUG 2024-11-18 19:19:34,255 INFO - Download file (size 4k) 2024-11-18 19:19:34,256 INFO 2024-11-18 19:19:34,256 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 19:19:34,256 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:34,533 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:34,533 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:34,533 DEBUG --- stdout --- 2024-11-18 19:19:34,533 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:34,533 DEBUG --- stderr --- 2024-11-18 19:19:34,533 DEBUG source : /results/orders/benchmark-80/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/cmd.txt 2024-11-18 19:19:34,533 INFO 2024-11-18 19:19:34,533 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-11-18 19:19:34,533 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:34,807 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:34,807 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:34,807 DEBUG --- stdout --- 2024-11-18 19:19:34,807 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:34,807 DEBUG --- stderr --- 2024-11-18 19:19:34,807 DEBUG 2024-11-18 19:19:34,808 INFO 2024-11-18 19:19:34,808 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-80/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/cmd.txt 2024-11-18 19:19:34,808 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 19:19:35,516 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:35,516 DEBUG --- stdout --- 2024-11-18 19:19:35,516 DEBUG tar: Removing leading `/' from member names 2024-11-18 19:19:35,516 DEBUG --- stderr --- 2024-11-18 19:19:35,516 DEBUG 2024-11-18 19:19:35,517 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/cmd.txt is small (1 lines), printing all lines: 2024-11-18 19:19:35,517 DEBUG ----- output ----- 2024-11-18 19:19:35,517 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-80" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=200000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=80 -Duser_password=Pa_ssw0rd" 2024-11-18 19:19:35,517 INFO ________________________________________________________________________________ [2024-11-18 19:19:35] benchmark-80 show_rc : Display rc order ________________________________________________________________________________ 2024-11-18 19:19:35,517 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/rc.txt is small (1 lines), printing all lines: 2024-11-18 19:19:35,517 DEBUG ----- output ----- 2024-11-18 19:19:35,517 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-18 19:19:35,517 INFO ________________________________________________________________________________ [2024-11-18 19:19:35] benchmark-80 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-18 19:19:35,523 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stdout.txt is 60477 lines): 2024-11-18 19:19:35,524 DEBUG ----- output ----- 2024-11-18 19:19:35,524 INFO 2024-11-18 18:18:58,530 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpvyb7m1c7 2024-11-18 19:19:35,524 INFO 2024-11-18 18:18:58,532 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-80" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=200000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=80 -Duser_password=**** 2024-11-18 19:19:35,524 INFO [INFO] Scanning for projects... 2024-11-18 19:19:35,524 INFO [INFO] 2024-11-18 19:19:35,524 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-18 19:19:35,524 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-18 19:19:35,524 INFO [INFO] from pom.xml 2024-11-18 19:19:35,524 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-18 19:19:35,524 INFO [INFO] 2024-11-18 19:19:35,524 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-18 19:19:35,524 INFO [INFO] 2024-11-18 19:19:35,524 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-18 19:19:35,524 INFO [INFO] skip non existing resourceDirectory /tmp/tmpvyb7m1c7/src/main/resources 2024-11-18 19:19:35,524 INFO [INFO] 2024-11-18 19:19:35,524 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-18 19:19:35,524 INFO [INFO] No sources to compile 2024-11-18 19:19:35,524 INFO [INFO] 2024-11-18 19:19:35,524 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-18 19:19:35,524 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-18 19:19:35,524 INFO [INFO] 2024-11-18 19:19:35,524 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-18 19:19:35,524 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-18 19:19:35,524 INFO [INFO] 2024-11-18 19:19:35,524 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-18 19:19:35,524 INFO [INFO] compiling 38 Scala sources to /tmp/tmpvyb7m1c7/target/test-classes ... 2024-11-18 19:19:35,524 INFO [INFO] compile in 6.8 s 2024-11-18 19:19:35,524 INFO [INFO] 2024-11-18 19:19:35,524 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-18 19:19:35,524 INFO [INFO] 2024-11-18 19:19:35,524 INFO [INFO] 2024-11-18 19:19:35,524 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-18 19:19:35,524 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-18 19:19:35,524 INFO Gatling 3.13.1 is available! (you're using 3.12.0) 2024-11-18 19:19:35,524 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-18 19:19:35,524 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-18 19:19:35,524 INFO 2024-11-18 19:19:35,524 INFO ================================================================================ 2024-11-18 19:19:35,524 INFO 2024-11-18 18:19:14 GMT 5s elapsed 2024-11-18 19:19:35,524 INFO ---- Requests ------------------------------------------------------------------ 2024-11-18 19:19:35,524 INFO > Global (OK=345 KO=0 ) 2024-11-18 19:19:35,524 INFO > restLoginInitiate (OK=33 KO=0 ) 2024-11-18 19:19:35,524 INFO > restLoginUsernamePasswordCallback (OK=31 KO=0 ) 2024-11-18 19:19:35,524 INFO > Skip 2FA (OK=18 KO=0 ) 2024-11-18 19:19:35,524 INFO > User Authorize PKCE stage (OK=36 KO=0 ) 2024-11-18 19:19:35,524 INFO > User AccessToken PKCE stage (OK=34 KO=0 ) 2024-11-18 19:19:35,524 INFO > GetUUid (OK=10 KO=0 ) 2024-11-18 19:19:35,524 INFO > GetOpenidConfig (OK=10 KO=0 ) 2024-11-18 19:19:35,524 INFO > CreatePolicy (OK=10 KO=0 ) 2024-11-18 19:19:35,524 INFO > RootUserinfo (OK=9 KO=0 ) 2024-11-18 19:19:35,524 INFO > tokenUserinfo (OK=5 KO=0 ) 2024-11-18 19:19:35,524 INFO > UI-Post-Login - stage 1 (OK=8 KO=0 ) 2024-11-18 19:19:35,524 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=8 KO=0 ) 2024-11-18 19:19:35,524 INFO > UI AM idFromSession (OK=8 KO=0 ) 2024-11-18 19:19:35,524 INFO > UI AM validateGoto (OK=8 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI-Post-Login - stage 2 (OK=7 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=7 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI Enduser appAuthHelper1 (OK=7 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI Enduser appAuthHelper2 (OK=7 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI-Post-Login - stage 3 (OK=6 KO=0 ) 2024-11-18 19:19:35,525 INFO > restloginProgressiveProfileCallback (OK=2 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=5 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI Enduser sessioncheck (OK=5 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI IDM uiconfig (OK=5 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI IDM version (OK=5 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI IDM privilege (OK=5 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI IDM login (OK=5 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI IDM features (OK=5 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI IDM dashboard (OK=4 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI IDM managed user schema (OK=4 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI IDM user (OK=4 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI IDM oidcToken (OK=4 KO=0 ) 2024-11-18 19:19:35,525 INFO > userinfo (OK=4 KO=0 ) 2024-11-18 19:19:35,525 INFO > getIdToken (OK=2 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI-Logout (OK=4 KO=0 ) 2024-11-18 19:19:35,525 INFO > getIdToken Redirect 1 (OK=2 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI-Logout Redirect 1 (OK=3 KO=0 ) 2024-11-18 19:19:35,525 INFO > createOrganization (OK=2 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI AM revoke1 (OK=3 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI AM endsession (OK=3 KO=0 ) 2024-11-18 19:19:35,525 INFO > UI AM revoke2 (OK=3 KO=0 ) 2024-11-18 19:19:35,525 INFO > queryOrganization (OK=2 KO=0 ) 2024-11-18 19:19:35,525 INFO > deleteOrganization (OK=2 KO=0 ) 2024-11-18 19:19:35,525 INFO 2024-11-18 19:19:35,525 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-18 19:19:35,525 INFO [- ] 0% 2024-11-18 19:19:35,525 INFO waiting: 288066 / active: 23 / done: 10 2024-11-18 19:19:35,525 INFO ================================================================================ 2024-11-18 19:19:35,525 INFO 2024-11-18 19:19:35,525 INFO 18:19:15.704 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 19:19:35,525 INFO 18:19:18.664 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 19:19:35,525 INFO 2024-11-18 19:19:35,525 INFO ================================================================================ 2024-11-18 19:19:35,525 INFO 2024-11-18 18:19:19 GMT 10s elapsed 2024-11-18 19:19:35,525 INFO ---- Requests ------------------------------------------------------------------ 2024-11-18 19:19:35,525 INFO > Global (OK=1642 KO=0 ) 2024-11-18 19:19:35,525 INFO > restLoginInitiate (OK=129 KO=0 ) 2024-11-18 19:19:35,525 INFO > restLoginUsernamePasswordCallback (OK=126 KO=0 ) 2024-11-18 19:19:35,525 INFO > Skip 2FA (OK=65 KO=0 ) 2024-11-18 19:19:35,525 INFO > User Authorize PKCE stage (OK=149 KO=0 ) 2024-11-18 19:19:35,525 INFO > User AccessToken PKCE stage (OK=148 KO=0 ) 2024-11-18 19:19:35,525 INFO [...] 2024-11-18 19:19:35,534 INFO headers: 2024-11-18 19:19:35,534 INFO user-agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 12_5_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/105.0.0.0 Safari/537.36 2024-11-18 19:19:35,534 INFO Accept-API-Version: resource=2.1, protocol=1.0 2024-11-18 19:19:35,534 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 19:19:35,534 INFO accept: */* 2024-11-18 19:19:35,534 INFO cookie: amlbcookie=01; 9b75a55744995d0=UNfSQmyexgTSgKqMHAEuIR282-8.*AAJTSQACMDIAAlNLABxlU1I1UGJYa1J5QTRNdWNsZ2hmYVBYK2s5Ylk9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 19:19:35,534 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-18 19:19:35,534 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 19:19:35,534 INFO content-length: 0 2024-11-18 19:19:35,534 INFO cookies: 2024-11-18 19:19:35,534 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 19:19:35,534 INFO 9b75a55744995d0=UNfSQmyexgTSgKqMHAEuIR282-8.*AAJTSQACMDIAAlNLABxlU1I1UGJYa1J5QTRNdWNsZ2hmYVBYK2s5Ylk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 19:19:35,534 INFO ========================= 2024-11-18 19:19:35,534 INFO HTTP response: 2024-11-18 19:19:35,534 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 19:19:35,534 INFO 19:19:09.411 [ERROR] i.g.c.a.b.SessionHookBuilder$$anon$1 - 'hook-190' crashed with 'j.u.NoSuchElementException: No attribute named 'callbacks' is defined', forwarding to the next one 2024-11-18 19:19:35,534 INFO 19:19:09.412 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM privilege' failed for user 285620: j.i.IOException: Premature close 2024-11-18 19:19:35,534 INFO 19:19:09.412 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 19:19:35,534 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 19:19:35,534 INFO Request: 2024-11-18 19:19:35,534 INFO UI IDM privilege: KO j.i.IOException: Premature close 2024-11-18 19:19:35,534 INFO ========================= 2024-11-18 19:19:35,534 INFO Session: 2024-11-18 19:19:35,534 INFO Session(idc.benchmark.Mix2024,285620,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTEwMzAyMDM4NSIsInN1Ym5hbWUiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJKVm9uWG9YalBhUkhvSEUyNjhUdmtxYm15WlUiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NTc1NDksImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NTc1NDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTYxMTQ5LCJpYXQiOjE3MzE5NTc1NDksImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IjZQX3FHVm1qeW9PZHNrTzY2eU53ZHR0RE1fNCJ9.0cHhTfOLnk_tvfTDW1iLXieBRaVSdz9jYz9R9yQ7myE, username -> user.49749, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTEwMzAyMTM1NCIsInN1Ym5hbWUiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJ1M0REVW5yUDBUMWREOFZhdnVPdWhFZENieDAiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NTc1NDksImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NTc1NDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTYxMTQ5LCJpYXQiOjE3MzE5NTc1NDksImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IlZaN0xVWkJXSExwVllUaGdwREUyYjF1RDlqNCJ9.pYH_hh5O47HS7KDjqhNPWgyaSMq8pRNg2MXzElsErlE, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@411bbde0, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> cAJ1jdyeATevGdEsbekuJDMrseU, callbacks -> {"tokenId":"-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731957548811), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731957548811))), user_uuid -> 3c8a5de2-d435-4038-a855-ca3be9b6f401, password -> Pa_ssw0rd, challenge -> AxwUQh0P5fr97YttmoGK9jt77kv3xx2npvwJrUBS-dI, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 79.111.175.76, verifier -> Njg5NDcyNDM4NTI4MTU0MjExOA, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7611944e, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@2a89970c, tokenId -> -5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007f3220698000@5961acbf,io.netty.channel.epoll.EpollEventLoop@239b0f9d) 2024-11-18 19:19:35,534 INFO ========================= 2024-11-18 19:19:35,534 INFO HTTP request: 2024-11-18 19:19:35,534 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/privilege?_action=listPrivileges 2024-11-18 19:19:35,534 INFO headers: 2024-11-18 19:19:35,534 INFO user-agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 12_5_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/105.0.0.0 Safari/537.36 2024-11-18 19:19:35,534 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 19:19:35,535 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTEwMzAyMTM1NCIsInN1Ym5hbWUiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJ1M0REVW5yUDBUMWREOFZhdnVPdWhFZENieDAiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NTc1NDksImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NTc1NDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTYxMTQ5LCJpYXQiOjE3MzE5NTc1NDksImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IlZaN0xVWkJXSExwVllUaGdwREUyYjF1RDlqNCJ9.pYH_hh5O47HS7KDjqhNPWgyaSMq8pRNg2MXzElsErlE 2024-11-18 19:19:35,535 INFO Accept-Api-Version: 2024-11-18 19:19:35,535 INFO accept: */* 2024-11-18 19:19:35,535 INFO cookie: amlbcookie=01; 9b75a55744995d0=-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 19:19:35,535 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-18 19:19:35,535 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 19:19:35,535 INFO content-length: 0 2024-11-18 19:19:35,535 INFO cookies: 2024-11-18 19:19:35,535 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 19:19:35,535 INFO 9b75a55744995d0=-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 19:19:35,535 INFO ========================= 2024-11-18 19:19:35,535 INFO HTTP response: 2024-11-18 19:19:35,535 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 19:19:35,535 INFO 19:19:09.412 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM features' failed for user 285620: j.i.IOException: Premature close 2024-11-18 19:19:35,535 INFO 19:19:09.412 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 19:19:35,535 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 19:19:35,535 INFO Request: 2024-11-18 19:19:35,535 INFO UI IDM features: KO j.i.IOException: Premature close 2024-11-18 19:19:35,535 INFO ========================= 2024-11-18 19:19:35,535 INFO Session: 2024-11-18 19:19:35,535 INFO Session(idc.benchmark.Mix2024,285620,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTEwMzAyMDM4NSIsInN1Ym5hbWUiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJKVm9uWG9YalBhUkhvSEUyNjhUdmtxYm15WlUiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NTc1NDksImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NTc1NDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTYxMTQ5LCJpYXQiOjE3MzE5NTc1NDksImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IjZQX3FHVm1qeW9PZHNrTzY2eU53ZHR0RE1fNCJ9.0cHhTfOLnk_tvfTDW1iLXieBRaVSdz9jYz9R9yQ7myE, username -> user.49749, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTEwMzAyMTM1NCIsInN1Ym5hbWUiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJ1M0REVW5yUDBUMWREOFZhdnVPdWhFZENieDAiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NTc1NDksImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NTc1NDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTYxMTQ5LCJpYXQiOjE3MzE5NTc1NDksImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IlZaN0xVWkJXSExwVllUaGdwREUyYjF1RDlqNCJ9.pYH_hh5O47HS7KDjqhNPWgyaSMq8pRNg2MXzElsErlE, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@411bbde0, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> cAJ1jdyeATevGdEsbekuJDMrseU, callbacks -> {"tokenId":"-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731957548811), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731957548811))), user_uuid -> 3c8a5de2-d435-4038-a855-ca3be9b6f401, password -> Pa_ssw0rd, challenge -> AxwUQh0P5fr97YttmoGK9jt77kv3xx2npvwJrUBS-dI, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 79.111.175.76, verifier -> Njg5NDcyNDM4NTI4MTU0MjExOA, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7611944e, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@2a89970c, tokenId -> -5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007f3220698000@5961acbf,io.netty.channel.epoll.EpollEventLoop@239b0f9d) 2024-11-18 19:19:35,535 INFO ========================= 2024-11-18 19:19:35,535 INFO HTTP request: 2024-11-18 19:19:35,535 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/features?_queryFilter=true 2024-11-18 19:19:35,535 INFO headers: 2024-11-18 19:19:35,535 INFO user-agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 12_5_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/105.0.0.0 Safari/537.36 2024-11-18 19:19:35,535 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 19:19:35,535 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTEwMzAyMTM1NCIsInN1Ym5hbWUiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJ1M0REVW5yUDBUMWREOFZhdnVPdWhFZENieDAiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NTc1NDksImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NTc1NDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTYxMTQ5LCJpYXQiOjE3MzE5NTc1NDksImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IlZaN0xVWkJXSExwVllUaGdwREUyYjF1RDlqNCJ9.pYH_hh5O47HS7KDjqhNPWgyaSMq8pRNg2MXzElsErlE 2024-11-18 19:19:35,535 INFO Accept-Api-Version: 2024-11-18 19:19:35,535 INFO accept: */* 2024-11-18 19:19:35,535 INFO cookie: amlbcookie=01; 9b75a55744995d0=-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 19:19:35,535 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 19:19:35,535 INFO cookies: 2024-11-18 19:19:35,535 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 19:19:35,535 INFO 9b75a55744995d0=-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 19:19:35,535 INFO ========================= 2024-11-18 19:19:35,535 INFO HTTP response: 2024-11-18 19:19:35,535 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 19:19:35,535 INFO 19:19:09.412 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM version' failed for user 285620: j.i.IOException: Premature close 2024-11-18 19:19:35,535 INFO 19:19:09.412 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 19:19:35,535 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 19:19:35,535 INFO Request: 2024-11-18 19:19:35,535 INFO UI IDM version: KO j.i.IOException: Premature close 2024-11-18 19:19:35,535 INFO ========================= 2024-11-18 19:19:35,535 INFO Session: 2024-11-18 19:19:35,535 INFO Session(idc.benchmark.Mix2024,285620,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTEwMzAyMDM4NSIsInN1Ym5hbWUiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJKVm9uWG9YalBhUkhvSEUyNjhUdmtxYm15WlUiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NTc1NDksImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NTc1NDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTYxMTQ5LCJpYXQiOjE3MzE5NTc1NDksImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IjZQX3FHVm1qeW9PZHNrTzY2eU53ZHR0RE1fNCJ9.0cHhTfOLnk_tvfTDW1iLXieBRaVSdz9jYz9R9yQ7myE, username -> user.49749, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTEwMzAyMTM1NCIsInN1Ym5hbWUiOiIzYzhhNWRlMi1kNDM1LTQwMzgtYTg1NS1jYTNiZTliNmY0MDEiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJ1M0REVW5yUDBUMWREOFZhdnVPdWhFZENieDAiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NTc1NDksImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NTc1NDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTYxMTQ5LCJpYXQiOjE3MzE5NTc1NDksImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IlZaN0xVWkJXSExwVllUaGdwREUyYjF1RDlqNCJ9.pYH_hh5O47HS7KDjqhNPWgyaSMq8pRNg2MXzElsErlE, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@411bbde0, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> cAJ1jdyeATevGdEsbekuJDMrseU, callbacks -> {"tokenId":"-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731957548811), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731957548811))), user_uuid -> 3c8a5de2-d435-4038-a855-ca3be9b6f401, password -> Pa_ssw0rd, challenge -> AxwUQh0P5fr97YttmoGK9jt77kv3xx2npvwJrUBS-dI, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 79.111.175.76, verifier -> Njg5NDcyNDM4NTI4MTU0MjExOA, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7611944e, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@2a89970c, tokenId -> -5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007f3220698000@5961acbf,io.netty.channel.epoll.EpollEventLoop@239b0f9d) 2024-11-18 19:19:35,535 INFO ========================= 2024-11-18 19:19:35,535 INFO HTTP request: 2024-11-18 19:19:35,535 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/version 2024-11-18 19:19:35,535 INFO headers: 2024-11-18 19:19:35,535 INFO user-agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 12_5_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/105.0.0.0 Safari/537.36 2024-11-18 19:19:35,535 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 19:19:35,535 INFO Accept-Api-Version: 2024-11-18 19:19:35,535 INFO accept: */* 2024-11-18 19:19:35,535 INFO cookie: amlbcookie=01; 9b75a55744995d0=-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 19:19:35,535 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 19:19:35,535 INFO cookies: 2024-11-18 19:19:35,535 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 19:19:35,535 INFO 9b75a55744995d0=-5apkNc6f0sjnVVI99Cy_OFpwPI.*AAJTSQACMDIAAlNLABxBZDNuaXJZM3lKN2pBalo5N0dJdjhsMUdaR3c9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 19:19:35,535 INFO ========================= 2024-11-18 19:19:35,535 INFO HTTP response: 2024-11-18 19:19:35,536 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 19:19:35,536 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 19:19:35,536 INFO [INFO] BUILD SUCCESS 2024-11-18 19:19:35,536 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 19:19:35,536 INFO [INFO] Total time: 01:00 h 2024-11-18 19:19:35,536 INFO [INFO] Finished at: 2024-11-18T19:19:09Z 2024-11-18 19:19:35,536 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 19:19:35,536 INFO 2024-11-18 19:19:09,527 INFO Gatling simulation run ended successfully 2024-11-18 19:19:35,536 INFO 2024-11-18 19:19:09,527 DEBUG No archive name provided. No archive will be created 2024-11-18 19:19:35,536 INFO ________________________________________________________________________________ [2024-11-18 19:19:35] benchmark-80 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-18 19:19:35,536 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stderr.txt is small (0 lines), printing all lines: 2024-11-18 19:19:35,536 DEBUG ----- output ----- 2024-11-18 19:19:35,536 DEBUG 2024-11-18 19:19:35,536 INFO ________________________________________________________________________________ [2024-11-18 19:19:35] benchmark-80 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-11-18 19:19:35] benchmark-80 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-18 19:19:35] benchmark-80 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped