--Task-- name: benchmark-30 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-25'] preceding_task: benchmark-25 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 30} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-21 21:13:23] benchmark-30 pre : N/A ________________________________________________________________________________ 2024-11-21 21:13:23 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-21 21:13:23 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-30/stdout.txt Pod log : /results/orders/benchmark-30 2024-11-21 21:13:23,648 INFO 2024-11-21 21:13:23,648 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-21 21:13:23,648 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:23,921 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:23,921 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:23,921 DEBUG --- stdout --- 2024-11-21 21:13:23,921 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:23,921 DEBUG --- stderr --- 2024-11-21 21:13:23,921 DEBUG ________________________________________________________________________________ [2024-11-21 21:13:23] benchmark-30 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-21 21:13:23,921 INFO 2024-11-21 21:13:23,921 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-21 21:13:23,921 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:24,193 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:24,193 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:24,193 DEBUG --- stdout --- 2024-11-21 21:13:24,193 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:24,193 DEBUG --- stderr --- 2024-11-21 21:13:24,193 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-30" --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 -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=30 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-21 21:13:24] benchmark-30 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-21 21:13:24,194 INFO 2024-11-21 21:13:24,194 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-21 21:13:24,194 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:24,472 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:24,472 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:24,472 DEBUG --- stdout --- 2024-11-21 21:13:24,472 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:24,472 DEBUG --- stderr --- 2024-11-21 21:13:24,472 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/order.json { "name": "benchmark-30", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-30\" --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 -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=30 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-30" } ________________________________________________________________________________ [2024-11-21 21:13:24] benchmark-30 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-21 21:13:24,475 INFO 2024-11-21 21:13:24,475 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-21 21:13:24,475 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-21 21:13:24,688 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-21 21:13:24,688 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:24,688 DEBUG --- stdout --- 2024-11-21 21:13:24,688 DEBUG WAITING 2024-11-21 21:13:24,688 DEBUG --- stderr --- 2024-11-21 21:13:24,688 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-21 21:13:24] benchmark-30 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-21 21:13:24,689 INFO 2024-11-21 21:13:24,689 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-21 21:13:24,689 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:24,961 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:24,961 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:24,961 DEBUG --- stdout --- 2024-11-21 21:13:24,961 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:24,961 DEBUG --- stderr --- 2024-11-21 21:13:24,961 DEBUG 2024-11-21 21:13:24,962 INFO 2024-11-21 21:13:24,962 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/04_benchmark-30/order.json overseer-0-6644bb6697-rchxr:/results/orders/order.json 2024-11-21 21:13:24,962 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 21:13:26,295 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:26,295 DEBUG --- stdout --- 2024-11-21 21:13:26,295 DEBUG 2024-11-21 21:13:26,295 DEBUG --- stderr --- 2024-11-21 21:13:26,295 DEBUG Order has been successfully forwarded to overseer 2024-11-21 21:13:26 - INFO: Check if Gatling simulation started successfully 2024-11-21 21:13:26,295 INFO 2024-11-21 21:13:26,295 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-21 21:13:26,295 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:26,567 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:26,567 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:26,567 DEBUG --- stdout --- 2024-11-21 21:13:26,567 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:26,568 DEBUG --- stderr --- 2024-11-21 21:13:26,568 DEBUG 2024-11-21 21:13:26,568 INFO 2024-11-21 21:13:26,568 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-30/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-21 21:13:26,568 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-21 21:13:27,285 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-21 21:13:33,006 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-21 21:13:38,728 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-21 21:13:38,728 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:38,728 DEBUG --- stdout --- 2024-11-21 21:13:38,728 DEBUG 2024-11-21 21:13:26,965 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpnpvvi6h7 2024-11-21 21:13:26,967 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-30" -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 -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=30 -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/tmpnpvvi6h7/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/tmpnpvvi6h7/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-21 21:13:38,728 DEBUG --- stderr --- 2024-11-21 21:13:38,728 DEBUG ________________________________________________________________________________ [2024-11-21 21:13:38] benchmark-30 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 02s, 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 03s, retry Order is running since 30m 04s, retry Order is running since 32m 04s, retry Order is running since 34m 04s, 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 05s, retry Order is running since 44m 06s, retry Order is running since 46m 06s, retry Order is running since 48m 06s, 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 07s, retry Order is running since 58m 08s, retry PASS : Order stopped by itself after 1h and before timeout (1h 12m) ________________________________________________________________________________ [2024-11-21 22:13:47] benchmark-30 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-21 22:13:47,280 INFO 2024-11-21 22:13:47,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-21 22:13:47,280 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:47,578 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:47,579 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:47,579 DEBUG --- stdout --- 2024-11-21 22:13:47,579 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:47,579 DEBUG --- stderr --- 2024-11-21 22:13:47,579 DEBUG 2024-11-21 22:13:47,579 INFO 2024-11-21 22:13:47,579 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-21 22:13:47,579 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 22:13:48,346 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:48,346 DEBUG --- stdout --- 2024-11-21 22:13:48,346 DEBUG 2024-11-21 22:13:48,346 DEBUG --- stderr --- 2024-11-21 22:13:48,346 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-21 22:13:48] benchmark-30 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-21 22:13:48 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/order.json 2024-11-21 22:13:48,347 INFO 2024-11-21 22:13:48,347 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-21 22:13:48,347 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:48,618 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:48,618 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:48,618 DEBUG --- stdout --- 2024-11-21 22:13:48,618 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:48,618 DEBUG --- stderr --- 2024-11-21 22:13:48,618 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-21 22:13:48,618 INFO 2024-11-21 22:13:48,618 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-21 22:13:48,618 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:48,906 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:48,906 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:48,906 DEBUG --- stdout --- 2024-11-21 22:13:48,906 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:48,906 DEBUG --- stderr --- 2024-11-21 22:13:48,906 DEBUG 2024-11-21 22:13:48,907 INFO 2024-11-21 22:13:48,907 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-30/rc.txt" ]; then echo "/results/orders/benchmark-30/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-30/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-30/rc.txt file found" 2024-11-21 22:13:48,907 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 22:13:49,697 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-21 22:13:49,697 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:49,697 DEBUG --- stdout --- 2024-11-21 22:13:49,697 DEBUG /results/orders/benchmark-30/rc.txt file found 2024-11-21 22:13:49,697 DEBUG --- stderr --- 2024-11-21 22:13:49,697 DEBUG 2024-11-21 22:13:49,698 INFO 2024-11-21 22:13:49,698 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-21 22:13:49,698 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:49,968 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:49,968 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:49,968 DEBUG --- stdout --- 2024-11-21 22:13:49,968 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:49,968 DEBUG --- stderr --- 2024-11-21 22:13:49,968 DEBUG 2024-11-21 22:13:49,968 INFO 2024-11-21 22:13:49,968 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-30/rc.txt 2024-11-21 22:13:50,744 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-21 22:13:50,744 DEBUG --- stdout --- 2024-11-21 22:13:50,744 DEBUG 4 /results/orders/benchmark-30/rc.txt 2024-11-21 22:13:50,744 DEBUG --- stderr --- 2024-11-21 22:13:50,744 DEBUG 2024-11-21 22:13:50,745 INFO - Download file (size 4k) 2024-11-21 22:13:50,745 INFO 2024-11-21 22:13:50,745 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-21 22:13:50,745 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:51,016 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:51,016 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:51,016 DEBUG --- stdout --- 2024-11-21 22:13:51,016 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:51,016 DEBUG --- stderr --- 2024-11-21 22:13:51,016 DEBUG source : /results/orders/benchmark-30/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/rc.txt 2024-11-21 22:13:51,016 INFO 2024-11-21 22:13:51,016 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-21 22:13:51,016 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:51,289 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:51,290 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:51,290 DEBUG --- stdout --- 2024-11-21 22:13:51,290 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:51,290 DEBUG --- stderr --- 2024-11-21 22:13:51,290 DEBUG 2024-11-21 22:13:51,290 INFO 2024-11-21 22:13:51,290 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-30/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/rc.txt 2024-11-21 22:13:51,290 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-21 22:13:52,033 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:52,033 DEBUG --- stdout --- 2024-11-21 22:13:52,034 DEBUG tar: Removing leading `/' from member names 2024-11-21 22:13:52,034 DEBUG --- stderr --- 2024-11-21 22:13:52,034 DEBUG 2024-11-21 22:13:52,034 INFO 2024-11-21 22:13:52,034 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-21 22:13:52,034 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:52,304 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:52,304 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:52,304 DEBUG --- stdout --- 2024-11-21 22:13:52,304 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:52,304 DEBUG --- stderr --- 2024-11-21 22:13:52,304 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-21 22:13:52,304 INFO 2024-11-21 22:13:52,305 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-21 22:13:52,305 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:52,577 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:52,577 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:52,577 DEBUG --- stdout --- 2024-11-21 22:13:52,577 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:52,578 DEBUG --- stderr --- 2024-11-21 22:13:52,578 DEBUG 2024-11-21 22:13:52,578 INFO 2024-11-21 22:13:52,578 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-30/stderr.txt" ]; then echo "/results/orders/benchmark-30/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-30/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-30/stderr.txt file found" 2024-11-21 22:13:52,578 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 22:13:53,357 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-21 22:13:53,357 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:53,357 DEBUG --- stdout --- 2024-11-21 22:13:53,357 DEBUG /results/orders/benchmark-30/stderr.txt file found 2024-11-21 22:13:53,357 DEBUG --- stderr --- 2024-11-21 22:13:53,357 DEBUG 2024-11-21 22:13:53,357 INFO 2024-11-21 22:13:53,357 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-21 22:13:53,357 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:53,630 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:53,630 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:53,630 DEBUG --- stdout --- 2024-11-21 22:13:53,630 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:53,630 DEBUG --- stderr --- 2024-11-21 22:13:53,630 DEBUG 2024-11-21 22:13:53,630 INFO 2024-11-21 22:13:53,631 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-30/stderr.txt 2024-11-21 22:13:54,419 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-21 22:13:54,419 DEBUG --- stdout --- 2024-11-21 22:13:54,419 DEBUG 0 /results/orders/benchmark-30/stderr.txt 2024-11-21 22:13:54,419 DEBUG --- stderr --- 2024-11-21 22:13:54,419 DEBUG 2024-11-21 22:13:54,419 INFO - Download file (size 0k) 2024-11-21 22:13:54,420 INFO 2024-11-21 22:13:54,420 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-21 22:13:54,420 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:54,692 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:54,692 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:54,692 DEBUG --- stdout --- 2024-11-21 22:13:54,692 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:54,693 DEBUG --- stderr --- 2024-11-21 22:13:54,693 DEBUG source : /results/orders/benchmark-30/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/stderr.txt 2024-11-21 22:13:54,693 INFO 2024-11-21 22:13:54,693 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-21 22:13:54,693 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:54,967 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:54,967 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:54,967 DEBUG --- stdout --- 2024-11-21 22:13:54,967 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:54,967 DEBUG --- stderr --- 2024-11-21 22:13:54,967 DEBUG 2024-11-21 22:13:54,967 INFO 2024-11-21 22:13:54,967 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-30/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/stderr.txt 2024-11-21 22:13:54,967 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-21 22:13:55,709 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:55,710 DEBUG --- stdout --- 2024-11-21 22:13:55,710 DEBUG tar: Removing leading `/' from member names 2024-11-21 22:13:55,710 DEBUG --- stderr --- 2024-11-21 22:13:55,710 DEBUG 2024-11-21 22:13:55,710 INFO 2024-11-21 22:13:55,710 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-21 22:13:55,710 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:55,982 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:55,982 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:55,982 DEBUG --- stdout --- 2024-11-21 22:13:55,982 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:55,982 DEBUG --- stderr --- 2024-11-21 22:13:55,983 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-21 22:13:55,983 INFO 2024-11-21 22:13:55,983 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-21 22:13:55,983 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:56,256 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:56,256 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:56,256 DEBUG --- stdout --- 2024-11-21 22:13:56,256 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:56,256 DEBUG --- stderr --- 2024-11-21 22:13:56,256 DEBUG 2024-11-21 22:13:56,256 INFO 2024-11-21 22:13:56,257 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-30/stdout.txt" ]; then echo "/results/orders/benchmark-30/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-30/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-30/stdout.txt file found" 2024-11-21 22:13:56,257 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 22:13:57,042 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-21 22:13:57,042 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:57,042 DEBUG --- stdout --- 2024-11-21 22:13:57,042 DEBUG /results/orders/benchmark-30/stdout.txt file found 2024-11-21 22:13:57,042 DEBUG --- stderr --- 2024-11-21 22:13:57,042 DEBUG 2024-11-21 22:13:57,042 INFO 2024-11-21 22:13:57,042 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-21 22:13:57,042 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:57,313 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:57,313 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:57,313 DEBUG --- stdout --- 2024-11-21 22:13:57,313 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:57,313 DEBUG --- stderr --- 2024-11-21 22:13:57,313 DEBUG 2024-11-21 22:13:57,313 INFO 2024-11-21 22:13:57,314 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-30/stdout.txt 2024-11-21 22:13:58,091 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-21 22:13:58,091 DEBUG --- stdout --- 2024-11-21 22:13:58,091 DEBUG 4144 /results/orders/benchmark-30/stdout.txt 2024-11-21 22:13:58,091 DEBUG --- stderr --- 2024-11-21 22:13:58,091 DEBUG 2024-11-21 22:13:58,091 INFO - Download file (size 4144k) 2024-11-21 22:13:58,091 INFO 2024-11-21 22:13:58,091 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-21 22:13:58,091 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:58,362 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:58,362 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:58,362 DEBUG --- stdout --- 2024-11-21 22:13:58,362 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:58,362 DEBUG --- stderr --- 2024-11-21 22:13:58,362 DEBUG source : /results/orders/benchmark-30/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/stdout.txt 2024-11-21 22:13:58,362 INFO 2024-11-21 22:13:58,362 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-21 22:13:58,362 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:13:58,637 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:13:58,637 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:58,637 DEBUG --- stdout --- 2024-11-21 22:13:58,637 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:13:58,637 DEBUG --- stderr --- 2024-11-21 22:13:58,637 DEBUG 2024-11-21 22:13:58,637 INFO 2024-11-21 22:13:58,637 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-30/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/stdout.txt 2024-11-21 22:13:58,637 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-21 22:13:59,840 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:13:59,840 DEBUG --- stdout --- 2024-11-21 22:13:59,840 DEBUG tar: Removing leading `/' from member names 2024-11-21 22:13:59,840 DEBUG --- stderr --- 2024-11-21 22:13:59,840 DEBUG ________________________________________________________________________________ [2024-11-21 22:13:59] benchmark-30 show_cmd : Display order command ________________________________________________________________________________ 2024-11-21 22:13:59,840 INFO 2024-11-21 22:13:59,840 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-21 22:13:59,840 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:14:00,112 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:14:00,112 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:14:00,112 DEBUG --- stdout --- 2024-11-21 22:14:00,112 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:14:00,112 DEBUG --- stderr --- 2024-11-21 22:14:00,112 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-21 22:14:00,112 INFO 2024-11-21 22:14:00,112 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-21 22:14:00,112 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:14:00,384 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:14:00,384 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:14:00,384 DEBUG --- stdout --- 2024-11-21 22:14:00,384 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:14:00,384 DEBUG --- stderr --- 2024-11-21 22:14:00,384 DEBUG 2024-11-21 22:14:00,385 INFO 2024-11-21 22:14:00,385 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-30/cmd.txt" ]; then echo "/results/orders/benchmark-30/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-30/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-30/cmd.txt file found" 2024-11-21 22:14:00,385 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 22:14:01,163 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-21 22:14:01,163 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:14:01,163 DEBUG --- stdout --- 2024-11-21 22:14:01,163 DEBUG /results/orders/benchmark-30/cmd.txt file found 2024-11-21 22:14:01,163 DEBUG --- stderr --- 2024-11-21 22:14:01,164 DEBUG 2024-11-21 22:14:01,164 INFO 2024-11-21 22:14:01,164 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-21 22:14:01,164 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:14:01,435 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:14:01,435 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:14:01,435 DEBUG --- stdout --- 2024-11-21 22:14:01,435 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:14:01,435 DEBUG --- stderr --- 2024-11-21 22:14:01,435 DEBUG 2024-11-21 22:14:01,435 INFO 2024-11-21 22:14:01,436 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-30/cmd.txt 2024-11-21 22:14:02,213 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-21 22:14:02,213 DEBUG --- stdout --- 2024-11-21 22:14:02,213 DEBUG 4 /results/orders/benchmark-30/cmd.txt 2024-11-21 22:14:02,214 DEBUG --- stderr --- 2024-11-21 22:14:02,214 DEBUG 2024-11-21 22:14:02,214 INFO - Download file (size 4k) 2024-11-21 22:14:02,214 INFO 2024-11-21 22:14:02,214 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-21 22:14:02,214 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:14:02,488 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:14:02,488 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:14:02,488 DEBUG --- stdout --- 2024-11-21 22:14:02,488 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:14:02,488 DEBUG --- stderr --- 2024-11-21 22:14:02,488 DEBUG source : /results/orders/benchmark-30/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/cmd.txt 2024-11-21 22:14:02,488 INFO 2024-11-21 22:14:02,488 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-21 22:14:02,488 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 22:14:02,761 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 22:14:02,761 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:14:02,761 DEBUG --- stdout --- 2024-11-21 22:14:02,761 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 22:14:02,761 DEBUG --- stderr --- 2024-11-21 22:14:02,762 DEBUG 2024-11-21 22:14:02,762 INFO 2024-11-21 22:14:02,762 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-30/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/cmd.txt 2024-11-21 22:14:02,762 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-21 22:14:03,504 INFO [loop_until]: OK (rc = 0) 2024-11-21 22:14:03,505 DEBUG --- stdout --- 2024-11-21 22:14:03,505 DEBUG tar: Removing leading `/' from member names 2024-11-21 22:14:03,505 DEBUG --- stderr --- 2024-11-21 22:14:03,505 DEBUG 2024-11-21 22:14:03,505 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/cmd.txt is small (1 lines), printing all lines: 2024-11-21 22:14:03,505 DEBUG ----- output ----- 2024-11-21 22:14:03,505 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-30" --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 -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=30 -Duser_password=Pa_ssw0rd" 2024-11-21 22:14:03,505 INFO ________________________________________________________________________________ [2024-11-21 22:14:03] benchmark-30 show_rc : Display rc order ________________________________________________________________________________ 2024-11-21 22:14:03,505 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/rc.txt is small (1 lines), printing all lines: 2024-11-21 22:14:03,505 DEBUG ----- output ----- 2024-11-21 22:14:03,505 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-21 22:14:03,505 INFO ________________________________________________________________________________ [2024-11-21 22:14:03] benchmark-30 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-21 22:14:03,511 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/04_benchmark-30/stdout.txt is 54088 lines): 2024-11-21 22:14:03,511 DEBUG ----- output ----- 2024-11-21 22:14:03,511 INFO 2024-11-21 21:13:26,965 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpnpvvi6h7 2024-11-21 22:14:03,511 INFO 2024-11-21 21:13:26,967 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-30" -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 -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=30 -Duser_password=**** 2024-11-21 22:14:03,511 INFO [INFO] Scanning for projects... 2024-11-21 22:14:03,511 INFO [INFO] 2024-11-21 22:14:03,511 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-21 22:14:03,511 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-21 22:14:03,511 INFO [INFO] from pom.xml 2024-11-21 22:14:03,511 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-21 22:14:03,511 INFO [INFO] 2024-11-21 22:14:03,511 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-21 22:14:03,511 INFO [INFO] 2024-11-21 22:14:03,511 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-21 22:14:03,511 INFO [INFO] skip non existing resourceDirectory /tmp/tmpnpvvi6h7/src/main/resources 2024-11-21 22:14:03,511 INFO [INFO] 2024-11-21 22:14:03,511 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-21 22:14:03,511 INFO [INFO] No sources to compile 2024-11-21 22:14:03,511 INFO [INFO] 2024-11-21 22:14:03,511 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-21 22:14:03,511 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-21 22:14:03,511 INFO [INFO] 2024-11-21 22:14:03,511 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-21 22:14:03,511 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-21 22:14:03,511 INFO [INFO] 2024-11-21 22:14:03,511 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-21 22:14:03,511 INFO [INFO] compiling 38 Scala sources to /tmp/tmpnpvvi6h7/target/test-classes ... 2024-11-21 22:14:03,511 INFO [INFO] compile in 6.8 s 2024-11-21 22:14:03,511 INFO [INFO] 2024-11-21 22:14:03,511 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-21 22:14:03,511 INFO [INFO] 2024-11-21 22:14:03,511 INFO [INFO] 2024-11-21 22:14:03,511 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-21 22:14:03,511 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-21 22:14:03,511 INFO Gatling 3.13.1 is available! (you're using 3.12.0) 2024-11-21 22:14:03,511 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-21 22:14:03,511 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-21 22:14:03,511 INFO 2024-11-21 22:14:03,511 INFO ================================================================================ 2024-11-21 22:14:03,511 INFO 2024-11-21 21:13:42 GMT 5s elapsed 2024-11-21 22:14:03,511 INFO ---- Requests ------------------------------------------------------------------ 2024-11-21 22:14:03,511 INFO > Global (OK=129 KO=0 ) 2024-11-21 22:14:03,512 INFO > restLoginInitiate (OK=12 KO=0 ) 2024-11-21 22:14:03,512 INFO > restLoginUsernamePasswordCallback (OK=12 KO=0 ) 2024-11-21 22:14:03,512 INFO > Skip 2FA (OK=7 KO=0 ) 2024-11-21 22:14:03,512 INFO > User Authorize PKCE stage (OK=13 KO=0 ) 2024-11-21 22:14:03,512 INFO > User AccessToken PKCE stage (OK=13 KO=0 ) 2024-11-21 22:14:03,512 INFO > GetUUid (OK=4 KO=0 ) 2024-11-21 22:14:03,512 INFO > GetOpenidConfig (OK=4 KO=0 ) 2024-11-21 22:14:03,512 INFO > CreatePolicy (OK=4 KO=0 ) 2024-11-21 22:14:03,512 INFO > RootUserinfo (OK=4 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI-Post-Login - stage 1 (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI AM idFromSession (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI AM validateGoto (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI-Post-Login - stage 2 (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI Enduser appAuthHelper2 (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI Enduser appAuthHelper1 (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI-Post-Login - stage 3 (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI Enduser sessioncheck (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI IDM uiconfig (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI IDM version (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI IDM features (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI IDM privilege (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI IDM login (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI IDM dashboard (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI IDM user (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI IDM managed user schema (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI IDM oidcToken (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > userinfo (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI-Logout (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI-Logout Redirect 1 (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI AM revoke2 (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI AM revoke1 (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI AM endsession (OK=2 KO=0 ) 2024-11-21 22:14:03,512 INFO > tokenUserinfo (OK=3 KO=0 ) 2024-11-21 22:14:03,512 INFO 2024-11-21 22:14:03,512 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-21 22:14:03,512 INFO [- ] 0% 2024-11-21 22:14:03,512 INFO waiting: 108015 / active: 7 / done: 5 2024-11-21 22:14:03,512 INFO ================================================================================ 2024-11-21 22:14:03,512 INFO 2024-11-21 22:14:03,512 INFO 21:13:45.300 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-21 22:14:03,512 INFO 2024-11-21 22:14:03,512 INFO ================================================================================ 2024-11-21 22:14:03,512 INFO 2024-11-21 21:13:47 GMT 10s elapsed 2024-11-21 22:14:03,512 INFO ---- Requests ------------------------------------------------------------------ 2024-11-21 22:14:03,512 INFO > Global (OK=623 KO=0 ) 2024-11-21 22:14:03,512 INFO > restLoginInitiate (OK=48 KO=0 ) 2024-11-21 22:14:03,512 INFO > restLoginUsernamePasswordCallback (OK=47 KO=0 ) 2024-11-21 22:14:03,512 INFO > Skip 2FA (OK=27 KO=0 ) 2024-11-21 22:14:03,512 INFO > User Authorize PKCE stage (OK=55 KO=0 ) 2024-11-21 22:14:03,512 INFO > User AccessToken PKCE stage (OK=54 KO=0 ) 2024-11-21 22:14:03,512 INFO > GetUUid (OK=19 KO=0 ) 2024-11-21 22:14:03,512 INFO > GetOpenidConfig (OK=23 KO=0 ) 2024-11-21 22:14:03,512 INFO > CreatePolicy (OK=18 KO=0 ) 2024-11-21 22:14:03,512 INFO > RootUserinfo (OK=17 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI-Post-Login - stage 1 (OK=12 KO=0 ) 2024-11-21 22:14:03,512 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=12 KO=0 ) 2024-11-21 22:14:03,512 INFO [...] 2024-11-21 22:14:03,520 INFO headers: 2024-11-21 22:14:03,520 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-21 22:14:03,520 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-21 22:14:03,520 INFO Accept-Api-Version: 2024-11-21 22:14:03,520 INFO accept: */* 2024-11-21 22:14:03,520 INFO cookie: amlbcookie=01; 9b75a55744995d0=Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-21 22:14:03,520 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-21 22:14:03,520 INFO cookies: 2024-11-21 22:14:03,520 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-21 22:14:03,520 INFO 9b75a55744995d0=Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-21 22:14:03,520 INFO ========================= 2024-11-21 22:14:03,520 INFO HTTP response: 2024-11-21 22:14:03,520 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-21 22:14:03,520 INFO 22:13:37.884 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 107185: j.i.IOException: Premature close 2024-11-21 22:14:03,520 INFO 22:13:37.884 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-21 22:14:03,520 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-21 22:14:03,520 INFO Request: 2024-11-21 22:14:03,520 INFO UI IDM login: KO j.i.IOException: Premature close 2024-11-21 22:14:03,520 INFO ========================= 2024-11-21 22:14:03,520 INFO Session: 2024-11-21 22:14:03,520 INFO Session(idc.benchmark.Mix2024,107185,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3YzU1ZjEyMC1mNGU5LTQ0MDItODQ0OS1mNGZlZjRmNTY4YTIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZjdlNTVmNzEtZmFhMi00ZGQxLWIwMzAtOGY0YmRkNTI3MzEwLTEwOTIyNzc0Iiwic3VibmFtZSI6IjdjNTVmMTIwLWY0ZTktNDQwMi04NDQ5LWY0ZmVmNGY1NjhhMiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImZkN1dfOFlXTGEtY1hwOU5rUFo5UHpFbWY2USIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMjIyNzIxNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMjIyNzIxNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzIyMzA4MTcsImlhdCI6MTczMjIyNzIxNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiWjVsZFFZZWJ3cUhmTDRSMVVUYjBkRnQ3YXRVIn0.XGJUrFtaIFP_4qyiYzMxw0dXSVfDdx4KHZ-bVyiFnv4, username -> user.145084, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3YzU1ZjEyMC1mNGU5LTQ0MDItODQ0OS1mNGZlZjRmNTY4YTIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMzljMTEzYzMtMDE2Ny00NWFlLThjNTEtZTJhNWZiNGU4Y2M2LTExMTUxNDEyIiwic3VibmFtZSI6IjdjNTVmMTIwLWY0ZTktNDQwMi04NDQ5LWY0ZmVmNGY1NjhhMiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IklidlhqWV9JNzM0eWt1WXdhZHR4anJUVlZBcyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMjIyNzIxNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMjIyNzIxNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzIyMzA4MTcsImlhdCI6MTczMjIyNzIxNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiQWdjeXNTNndoM1hlNXpIX0hXT3Q2eFdTYWVVIn0.libOGT93PllfnE0aV5LhKfkUT1l_JBQ1DVMmTM0ue7o, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@38da9e19, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> IbQKCcxlQkuVCKy3CBVbLwF7oqM, callbacks -> {"tokenId":"Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1732227217332), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1732227217332))), user_uuid -> 7c55f120-f4e9-4402-8449-f4fef4f568a2, password -> Pa_ssw0rd, challenge -> nQjRjK-HLuTHA9nfVuOoPQXrdfFWAyaa0rI2vM1ZVSk, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 161.0.79.203, verifier -> MjMwNDgyNDA0NzAyNjQ2MjM1MQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@60a6d631, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@20bf5b7a, tokenId -> Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, 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/0x00007b1fdc69a0e0@77417dee,io.netty.channel.epoll.EpollEventLoop@f79a760) 2024-11-21 22:14:03,520 INFO ========================= 2024-11-21 22:14:03,520 INFO HTTP request: 2024-11-21 22:14:03,520 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-21 22:14:03,520 INFO headers: 2024-11-21 22:14:03,520 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-21 22:14:03,520 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-21 22:14:03,520 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3YzU1ZjEyMC1mNGU5LTQ0MDItODQ0OS1mNGZlZjRmNTY4YTIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMzljMTEzYzMtMDE2Ny00NWFlLThjNTEtZTJhNWZiNGU4Y2M2LTExMTUxNDEyIiwic3VibmFtZSI6IjdjNTVmMTIwLWY0ZTktNDQwMi04NDQ5LWY0ZmVmNGY1NjhhMiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IklidlhqWV9JNzM0eWt1WXdhZHR4anJUVlZBcyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMjIyNzIxNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMjIyNzIxNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzIyMzA4MTcsImlhdCI6MTczMjIyNzIxNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiQWdjeXNTNndoM1hlNXpIX0hXT3Q2eFdTYWVVIn0.libOGT93PllfnE0aV5LhKfkUT1l_JBQ1DVMmTM0ue7o 2024-11-21 22:14:03,520 INFO Accept-Api-Version: 2024-11-21 22:14:03,520 INFO accept: */* 2024-11-21 22:14:03,520 INFO cookie: amlbcookie=01; 9b75a55744995d0=Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-21 22:14:03,520 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-21 22:14:03,520 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-21 22:14:03,520 INFO content-length: 0 2024-11-21 22:14:03,521 INFO cookies: 2024-11-21 22:14:03,521 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-21 22:14:03,521 INFO 9b75a55744995d0=Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-21 22:14:03,521 INFO ========================= 2024-11-21 22:14:03,521 INFO HTTP response: 2024-11-21 22:14:03,521 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-21 22:14:03,521 INFO 22:13:37.884 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM features' failed for user 107185: j.i.IOException: Premature close 2024-11-21 22:14:03,521 INFO 22:13:37.884 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-21 22:14:03,521 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-21 22:14:03,521 INFO Request: 2024-11-21 22:14:03,521 INFO UI IDM features: KO j.i.IOException: Premature close 2024-11-21 22:14:03,521 INFO ========================= 2024-11-21 22:14:03,521 INFO Session: 2024-11-21 22:14:03,521 INFO Session(idc.benchmark.Mix2024,107185,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3YzU1ZjEyMC1mNGU5LTQ0MDItODQ0OS1mNGZlZjRmNTY4YTIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZjdlNTVmNzEtZmFhMi00ZGQxLWIwMzAtOGY0YmRkNTI3MzEwLTEwOTIyNzc0Iiwic3VibmFtZSI6IjdjNTVmMTIwLWY0ZTktNDQwMi04NDQ5LWY0ZmVmNGY1NjhhMiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImZkN1dfOFlXTGEtY1hwOU5rUFo5UHpFbWY2USIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMjIyNzIxNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMjIyNzIxNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzIyMzA4MTcsImlhdCI6MTczMjIyNzIxNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiWjVsZFFZZWJ3cUhmTDRSMVVUYjBkRnQ3YXRVIn0.XGJUrFtaIFP_4qyiYzMxw0dXSVfDdx4KHZ-bVyiFnv4, username -> user.145084, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3YzU1ZjEyMC1mNGU5LTQ0MDItODQ0OS1mNGZlZjRmNTY4YTIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMzljMTEzYzMtMDE2Ny00NWFlLThjNTEtZTJhNWZiNGU4Y2M2LTExMTUxNDEyIiwic3VibmFtZSI6IjdjNTVmMTIwLWY0ZTktNDQwMi04NDQ5LWY0ZmVmNGY1NjhhMiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IklidlhqWV9JNzM0eWt1WXdhZHR4anJUVlZBcyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMjIyNzIxNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMjIyNzIxNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzIyMzA4MTcsImlhdCI6MTczMjIyNzIxNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiQWdjeXNTNndoM1hlNXpIX0hXT3Q2eFdTYWVVIn0.libOGT93PllfnE0aV5LhKfkUT1l_JBQ1DVMmTM0ue7o, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@38da9e19, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> IbQKCcxlQkuVCKy3CBVbLwF7oqM, callbacks -> {"tokenId":"Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1732227217332), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1732227217332))), user_uuid -> 7c55f120-f4e9-4402-8449-f4fef4f568a2, password -> Pa_ssw0rd, challenge -> nQjRjK-HLuTHA9nfVuOoPQXrdfFWAyaa0rI2vM1ZVSk, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 161.0.79.203, verifier -> MjMwNDgyNDA0NzAyNjQ2MjM1MQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@60a6d631, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@20bf5b7a, tokenId -> Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, 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/0x00007b1fdc69a0e0@77417dee,io.netty.channel.epoll.EpollEventLoop@f79a760) 2024-11-21 22:14:03,521 INFO ========================= 2024-11-21 22:14:03,521 INFO HTTP request: 2024-11-21 22:14:03,521 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/features?_queryFilter=true 2024-11-21 22:14:03,521 INFO headers: 2024-11-21 22:14:03,521 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-21 22:14:03,521 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-21 22:14:03,521 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3YzU1ZjEyMC1mNGU5LTQ0MDItODQ0OS1mNGZlZjRmNTY4YTIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMzljMTEzYzMtMDE2Ny00NWFlLThjNTEtZTJhNWZiNGU4Y2M2LTExMTUxNDEyIiwic3VibmFtZSI6IjdjNTVmMTIwLWY0ZTktNDQwMi04NDQ5LWY0ZmVmNGY1NjhhMiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IklidlhqWV9JNzM0eWt1WXdhZHR4anJUVlZBcyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMjIyNzIxNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMjIyNzIxNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzIyMzA4MTcsImlhdCI6MTczMjIyNzIxNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiQWdjeXNTNndoM1hlNXpIX0hXT3Q2eFdTYWVVIn0.libOGT93PllfnE0aV5LhKfkUT1l_JBQ1DVMmTM0ue7o 2024-11-21 22:14:03,521 INFO Accept-Api-Version: 2024-11-21 22:14:03,521 INFO accept: */* 2024-11-21 22:14:03,521 INFO cookie: amlbcookie=01; 9b75a55744995d0=Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-21 22:14:03,521 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-21 22:14:03,521 INFO cookies: 2024-11-21 22:14:03,521 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-21 22:14:03,521 INFO 9b75a55744995d0=Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-21 22:14:03,521 INFO ========================= 2024-11-21 22:14:03,521 INFO HTTP response: 2024-11-21 22:14:03,521 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-21 22:14:03,521 INFO 22:13:37.885 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM privilege' failed for user 107185: j.i.IOException: Premature close 2024-11-21 22:14:03,521 INFO 22:13:37.885 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-21 22:14:03,521 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-21 22:14:03,521 INFO Request: 2024-11-21 22:14:03,521 INFO UI IDM privilege: KO j.i.IOException: Premature close 2024-11-21 22:14:03,521 INFO ========================= 2024-11-21 22:14:03,521 INFO Session: 2024-11-21 22:14:03,521 INFO Session(idc.benchmark.Mix2024,107185,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3YzU1ZjEyMC1mNGU5LTQ0MDItODQ0OS1mNGZlZjRmNTY4YTIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZjdlNTVmNzEtZmFhMi00ZGQxLWIwMzAtOGY0YmRkNTI3MzEwLTEwOTIyNzc0Iiwic3VibmFtZSI6IjdjNTVmMTIwLWY0ZTktNDQwMi04NDQ5LWY0ZmVmNGY1NjhhMiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImZkN1dfOFlXTGEtY1hwOU5rUFo5UHpFbWY2USIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMjIyNzIxNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMjIyNzIxNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzIyMzA4MTcsImlhdCI6MTczMjIyNzIxNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiWjVsZFFZZWJ3cUhmTDRSMVVUYjBkRnQ3YXRVIn0.XGJUrFtaIFP_4qyiYzMxw0dXSVfDdx4KHZ-bVyiFnv4, username -> user.145084, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3YzU1ZjEyMC1mNGU5LTQ0MDItODQ0OS1mNGZlZjRmNTY4YTIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMzljMTEzYzMtMDE2Ny00NWFlLThjNTEtZTJhNWZiNGU4Y2M2LTExMTUxNDEyIiwic3VibmFtZSI6IjdjNTVmMTIwLWY0ZTktNDQwMi04NDQ5LWY0ZmVmNGY1NjhhMiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IklidlhqWV9JNzM0eWt1WXdhZHR4anJUVlZBcyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMjIyNzIxNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMjIyNzIxNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzIyMzA4MTcsImlhdCI6MTczMjIyNzIxNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiQWdjeXNTNndoM1hlNXpIX0hXT3Q2eFdTYWVVIn0.libOGT93PllfnE0aV5LhKfkUT1l_JBQ1DVMmTM0ue7o, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@38da9e19, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> IbQKCcxlQkuVCKy3CBVbLwF7oqM, callbacks -> {"tokenId":"Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1732227217332), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1732227217332))), user_uuid -> 7c55f120-f4e9-4402-8449-f4fef4f568a2, password -> Pa_ssw0rd, challenge -> nQjRjK-HLuTHA9nfVuOoPQXrdfFWAyaa0rI2vM1ZVSk, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 161.0.79.203, verifier -> MjMwNDgyNDA0NzAyNjQ2MjM1MQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@60a6d631, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@20bf5b7a, tokenId -> Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, 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/0x00007b1fdc69a0e0@77417dee,io.netty.channel.epoll.EpollEventLoop@f79a760) 2024-11-21 22:14:03,521 INFO ========================= 2024-11-21 22:14:03,521 INFO HTTP request: 2024-11-21 22:14:03,521 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/privilege?_action=listPrivileges 2024-11-21 22:14:03,521 INFO headers: 2024-11-21 22:14:03,521 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-21 22:14:03,521 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-21 22:14:03,521 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3YzU1ZjEyMC1mNGU5LTQ0MDItODQ0OS1mNGZlZjRmNTY4YTIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMzljMTEzYzMtMDE2Ny00NWFlLThjNTEtZTJhNWZiNGU4Y2M2LTExMTUxNDEyIiwic3VibmFtZSI6IjdjNTVmMTIwLWY0ZTktNDQwMi04NDQ5LWY0ZmVmNGY1NjhhMiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IklidlhqWV9JNzM0eWt1WXdhZHR4anJUVlZBcyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMjIyNzIxNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMjIyNzIxNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzIyMzA4MTcsImlhdCI6MTczMjIyNzIxNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiQWdjeXNTNndoM1hlNXpIX0hXT3Q2eFdTYWVVIn0.libOGT93PllfnE0aV5LhKfkUT1l_JBQ1DVMmTM0ue7o 2024-11-21 22:14:03,521 INFO Accept-Api-Version: 2024-11-21 22:14:03,521 INFO accept: */* 2024-11-21 22:14:03,521 INFO cookie: amlbcookie=01; 9b75a55744995d0=Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-21 22:14:03,521 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-21 22:14:03,521 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-21 22:14:03,521 INFO content-length: 0 2024-11-21 22:14:03,521 INFO cookies: 2024-11-21 22:14:03,521 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-21 22:14:03,521 INFO 9b75a55744995d0=Vmlr0fkQocZMxm-poyMdOKe7gd0.*AAJTSQACMDIAAlNLABxwS2czZWtRaUQ0WXg4Sm0veHkzc2tHZHBKUjg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-21 22:14:03,521 INFO ========================= 2024-11-21 22:14:03,521 INFO HTTP response: 2024-11-21 22:14:03,521 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-21 22:14:03,521 INFO [INFO] ------------------------------------------------------------------------ 2024-11-21 22:14:03,521 INFO [INFO] BUILD SUCCESS 2024-11-21 22:14:03,522 INFO [INFO] ------------------------------------------------------------------------ 2024-11-21 22:14:03,522 INFO [INFO] Total time: 01:00 h 2024-11-21 22:14:03,522 INFO [INFO] Finished at: 2024-11-21T22:13:37Z 2024-11-21 22:14:03,522 INFO [INFO] ------------------------------------------------------------------------ 2024-11-21 22:14:03,522 INFO 2024-11-21 22:13:38,000 INFO Gatling simulation run ended successfully 2024-11-21 22:14:03,522 INFO 2024-11-21 22:13:38,000 DEBUG No archive name provided. No archive will be created 2024-11-21 22:14:03,522 INFO ________________________________________________________________________________ [2024-11-21 22:14:03] benchmark-30 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-21 22:14:03,522 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-30/stderr.txt is small (0 lines), printing all lines: 2024-11-21 22:14:03,522 DEBUG ----- output ----- 2024-11-21 22:14:03,522 DEBUG 2024-11-21 22:14:03,522 INFO ________________________________________________________________________________ [2024-11-21 22:14:03] benchmark-30 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-21 22:14:03] benchmark-30 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-21 22:14:03] benchmark-30 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped