--Task-- name: benchmark-140 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-120'] preceding_task: benchmark-120 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self1r460-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 140} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-18 20:20:17] benchmark-140 pre : N/A ________________________________________________________________________________ 2024-11-18 20:20:17 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-18 20:20:17 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-140/stdout.txt Pod log : /results/orders/benchmark-140 2024-11-18 20:20:17,397 INFO 2024-11-18 20:20:17,397 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 20:20:17,397 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:17,672 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:17,672 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:17,672 DEBUG --- stdout --- 2024-11-18 20:20:17,672 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:17,672 DEBUG --- stderr --- 2024-11-18 20:20:17,672 DEBUG ________________________________________________________________________________ [2024-11-18 20:20:17] benchmark-140 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-18 20:20:17,673 INFO 2024-11-18 20:20:17,673 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 20:20:17,673 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:17,945 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:17,945 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:17,945 DEBUG --- stdout --- 2024-11-18 20:20:17,945 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:17,945 DEBUG --- stderr --- 2024-11-18 20:20:17,945 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-140" --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=self1r460- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=140 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-18 20:20:17] benchmark-140 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-18 20:20:17,946 INFO 2024-11-18 20:20:17,946 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 20:20:17,946 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:18,217 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:18,217 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:18,217 DEBUG --- stdout --- 2024-11-18 20:20:18,217 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:18,217 DEBUG --- stderr --- 2024-11-18 20:20:18,217 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/order.json { "name": "benchmark-140", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-140\" --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=self1r460- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=140 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-140" } ________________________________________________________________________________ [2024-11-18 20:20:18] benchmark-140 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 20:20:18,220 INFO 2024-11-18 20:20:18,220 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-18 20:20:18,220 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 20:20:18,433 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 20:20:18,433 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:18,433 DEBUG --- stdout --- 2024-11-18 20:20:18,433 DEBUG WAITING 2024-11-18 20:20:18,433 DEBUG --- stderr --- 2024-11-18 20:20:18,433 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 20:20:18] benchmark-140 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-18 20:20:18,434 INFO 2024-11-18 20:20:18,434 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 20:20:18,434 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:18,708 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:18,708 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:18,708 DEBUG --- stdout --- 2024-11-18 20:20:18,708 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:18,708 DEBUG --- stderr --- 2024-11-18 20:20:18,708 DEBUG 2024-11-18 20:20:18,709 INFO 2024-11-18 20:20:18,709 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/05_benchmark-140/order.json overseer-0-6644bb6697-rchxr:/results/orders/order.json 2024-11-18 20:20:18,709 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 20:20:20,043 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:20,043 DEBUG --- stdout --- 2024-11-18 20:20:20,043 DEBUG 2024-11-18 20:20:20,043 DEBUG --- stderr --- 2024-11-18 20:20:20,044 DEBUG Order has been successfully forwarded to overseer 2024-11-18 20:20:20 - INFO: Check if Gatling simulation started successfully 2024-11-18 20:20:20,044 INFO 2024-11-18 20:20:20,044 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 20:20:20,044 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:20,316 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:20,316 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:20,316 DEBUG --- stdout --- 2024-11-18 20:20:20,316 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:20,316 DEBUG --- stderr --- 2024-11-18 20:20:20,316 DEBUG 2024-11-18 20:20:20,316 INFO 2024-11-18 20:20:20,316 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-140/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-18 20:20:20,316 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 20:20:21,033 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 20:20:26,750 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 20:20:32,465 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-18 20:20:32,465 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:32,465 DEBUG --- stdout --- 2024-11-18 20:20:32,465 DEBUG 2024-11-18 20:20:20,652 DEBUG Creating results folder /results/gatling/benchmark-140 2024-11-18 20:20:20,660 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp7mc7y61a 2024-11-18 20:20:20,662 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-140" -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=self1r460- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=140 -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/tmp7mc7y61a/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/tmp7mc7y61a/target/test-classes ... [INFO] compile in 7.2 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 20:20:32,465 DEBUG --- stderr --- 2024-11-18 20:20:32,465 DEBUG ________________________________________________________________________________ [2024-11-18 20:20:32] benchmark-140 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 01s, 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 04s, 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 06s, 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 08s, retry Order is running since 58m 08s, retry PASS : Order stopped by itself after 1h and before timeout (1h 12m) ________________________________________________________________________________ [2024-11-18 21:20:41] benchmark-140 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-18 21:20:41,035 INFO 2024-11-18 21:20:41,036 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 21:20:41,036 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:41,331 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:41,331 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:41,331 DEBUG --- stdout --- 2024-11-18 21:20:41,331 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:41,331 DEBUG --- stderr --- 2024-11-18 21:20:41,331 DEBUG 2024-11-18 21:20:41,331 INFO 2024-11-18 21:20:41,331 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 21:20:41,331 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 21:20:42,094 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:42,094 DEBUG --- stdout --- 2024-11-18 21:20:42,094 DEBUG 2024-11-18 21:20:42,094 DEBUG --- stderr --- 2024-11-18 21:20:42,094 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-18 21:20:42] benchmark-140 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-18 21:20:42 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/order.json 2024-11-18 21:20:42,094 INFO 2024-11-18 21:20:42,094 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 21:20:42,094 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:42,369 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:42,369 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:42,369 DEBUG --- stdout --- 2024-11-18 21:20:42,369 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:42,369 DEBUG --- stderr --- 2024-11-18 21:20:42,369 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 21:20:42,369 INFO 2024-11-18 21:20:42,369 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 21:20:42,369 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:42,644 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:42,645 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:42,645 DEBUG --- stdout --- 2024-11-18 21:20:42,645 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:42,645 DEBUG --- stderr --- 2024-11-18 21:20:42,645 DEBUG 2024-11-18 21:20:42,645 INFO 2024-11-18 21:20:42,645 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-140/rc.txt" ]; then echo "/results/orders/benchmark-140/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-140/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-140/rc.txt file found" 2024-11-18 21:20:42,645 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 21:20:43,423 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 21:20:43,423 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:43,423 DEBUG --- stdout --- 2024-11-18 21:20:43,423 DEBUG /results/orders/benchmark-140/rc.txt file found 2024-11-18 21:20:43,423 DEBUG --- stderr --- 2024-11-18 21:20:43,423 DEBUG 2024-11-18 21:20:43,423 INFO 2024-11-18 21:20:43,423 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 21:20:43,423 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:43,696 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:43,697 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:43,697 DEBUG --- stdout --- 2024-11-18 21:20:43,697 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:43,697 DEBUG --- stderr --- 2024-11-18 21:20:43,697 DEBUG 2024-11-18 21:20:43,697 INFO 2024-11-18 21:20:43,697 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-140/rc.txt 2024-11-18 21:20:44,483 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 21:20:44,483 DEBUG --- stdout --- 2024-11-18 21:20:44,483 DEBUG 4 /results/orders/benchmark-140/rc.txt 2024-11-18 21:20:44,484 DEBUG --- stderr --- 2024-11-18 21:20:44,484 DEBUG 2024-11-18 21:20:44,484 INFO - Download file (size 4k) 2024-11-18 21:20:44,484 INFO 2024-11-18 21:20:44,484 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 21:20:44,484 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:44,756 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:44,756 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:44,756 DEBUG --- stdout --- 2024-11-18 21:20:44,756 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:44,756 DEBUG --- stderr --- 2024-11-18 21:20:44,756 DEBUG source : /results/orders/benchmark-140/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/rc.txt 2024-11-18 21:20:44,757 INFO 2024-11-18 21:20:44,757 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 21:20:44,757 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:45,028 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:45,028 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:45,028 DEBUG --- stdout --- 2024-11-18 21:20:45,028 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:45,028 DEBUG --- stderr --- 2024-11-18 21:20:45,028 DEBUG 2024-11-18 21:20:45,029 INFO 2024-11-18 21:20:45,029 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-140/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/rc.txt 2024-11-18 21:20:45,029 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 21:20:45,774 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:45,774 DEBUG --- stdout --- 2024-11-18 21:20:45,774 DEBUG tar: Removing leading `/' from member names 2024-11-18 21:20:45,774 DEBUG --- stderr --- 2024-11-18 21:20:45,774 DEBUG 2024-11-18 21:20:45,775 INFO 2024-11-18 21:20:45,775 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 21:20:45,775 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:46,046 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:46,046 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:46,046 DEBUG --- stdout --- 2024-11-18 21:20:46,046 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:46,046 DEBUG --- stderr --- 2024-11-18 21:20:46,046 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 21:20:46,046 INFO 2024-11-18 21:20:46,046 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 21:20:46,046 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:46,319 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:46,319 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:46,319 DEBUG --- stdout --- 2024-11-18 21:20:46,319 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:46,319 DEBUG --- stderr --- 2024-11-18 21:20:46,319 DEBUG 2024-11-18 21:20:46,320 INFO 2024-11-18 21:20:46,320 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-140/stderr.txt" ]; then echo "/results/orders/benchmark-140/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-140/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-140/stderr.txt file found" 2024-11-18 21:20:46,320 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 21:20:47,098 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 21:20:47,098 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:47,098 DEBUG --- stdout --- 2024-11-18 21:20:47,098 DEBUG /results/orders/benchmark-140/stderr.txt file found 2024-11-18 21:20:47,098 DEBUG --- stderr --- 2024-11-18 21:20:47,098 DEBUG 2024-11-18 21:20:47,099 INFO 2024-11-18 21:20:47,099 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 21:20:47,099 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:47,374 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:47,374 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:47,374 DEBUG --- stdout --- 2024-11-18 21:20:47,374 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:47,374 DEBUG --- stderr --- 2024-11-18 21:20:47,374 DEBUG 2024-11-18 21:20:47,374 INFO 2024-11-18 21:20:47,375 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-140/stderr.txt 2024-11-18 21:20:48,165 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 21:20:48,165 DEBUG --- stdout --- 2024-11-18 21:20:48,165 DEBUG 0 /results/orders/benchmark-140/stderr.txt 2024-11-18 21:20:48,165 DEBUG --- stderr --- 2024-11-18 21:20:48,165 DEBUG 2024-11-18 21:20:48,165 INFO - Download file (size 0k) 2024-11-18 21:20:48,166 INFO 2024-11-18 21:20:48,166 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 21:20:48,166 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:48,436 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:48,436 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:48,436 DEBUG --- stdout --- 2024-11-18 21:20:48,436 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:48,436 DEBUG --- stderr --- 2024-11-18 21:20:48,436 DEBUG source : /results/orders/benchmark-140/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/stderr.txt 2024-11-18 21:20:48,436 INFO 2024-11-18 21:20:48,436 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 21:20:48,436 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:48,707 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:48,707 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:48,707 DEBUG --- stdout --- 2024-11-18 21:20:48,707 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:48,707 DEBUG --- stderr --- 2024-11-18 21:20:48,707 DEBUG 2024-11-18 21:20:48,708 INFO 2024-11-18 21:20:48,708 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-140/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/stderr.txt 2024-11-18 21:20:48,708 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 21:20:49,449 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:49,450 DEBUG --- stdout --- 2024-11-18 21:20:49,450 DEBUG tar: Removing leading `/' from member names 2024-11-18 21:20:49,450 DEBUG --- stderr --- 2024-11-18 21:20:49,450 DEBUG 2024-11-18 21:20:49,450 INFO 2024-11-18 21:20:49,450 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 21:20:49,450 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:49,723 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:49,723 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:49,723 DEBUG --- stdout --- 2024-11-18 21:20:49,723 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:49,723 DEBUG --- stderr --- 2024-11-18 21:20:49,723 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 21:20:49,723 INFO 2024-11-18 21:20:49,723 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 21:20:49,723 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:49,994 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:49,994 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:49,994 DEBUG --- stdout --- 2024-11-18 21:20:49,994 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:49,994 DEBUG --- stderr --- 2024-11-18 21:20:49,994 DEBUG 2024-11-18 21:20:49,995 INFO 2024-11-18 21:20:49,995 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-140/stdout.txt" ]; then echo "/results/orders/benchmark-140/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-140/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-140/stdout.txt file found" 2024-11-18 21:20:49,995 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 21:20:50,770 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 21:20:50,770 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:50,770 DEBUG --- stdout --- 2024-11-18 21:20:50,770 DEBUG /results/orders/benchmark-140/stdout.txt file found 2024-11-18 21:20:50,770 DEBUG --- stderr --- 2024-11-18 21:20:50,770 DEBUG 2024-11-18 21:20:50,770 INFO 2024-11-18 21:20:50,770 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 21:20:50,770 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:51,042 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:51,042 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:51,043 DEBUG --- stdout --- 2024-11-18 21:20:51,043 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:51,043 DEBUG --- stderr --- 2024-11-18 21:20:51,043 DEBUG 2024-11-18 21:20:51,043 INFO 2024-11-18 21:20:51,043 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-140/stdout.txt 2024-11-18 21:20:51,821 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 21:20:51,821 DEBUG --- stdout --- 2024-11-18 21:20:51,821 DEBUG 9024 /results/orders/benchmark-140/stdout.txt 2024-11-18 21:20:51,821 DEBUG --- stderr --- 2024-11-18 21:20:51,821 DEBUG 2024-11-18 21:20:51,821 INFO - Download file (size 9024k) 2024-11-18 21:20:51,822 INFO 2024-11-18 21:20:51,822 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 21:20:51,822 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:52,092 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:52,092 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:52,092 DEBUG --- stdout --- 2024-11-18 21:20:52,092 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:52,092 DEBUG --- stderr --- 2024-11-18 21:20:52,092 DEBUG source : /results/orders/benchmark-140/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/stdout.txt 2024-11-18 21:20:52,093 INFO 2024-11-18 21:20:52,093 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 21:20:52,093 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:52,365 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:52,365 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:52,365 DEBUG --- stdout --- 2024-11-18 21:20:52,365 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:52,365 DEBUG --- stderr --- 2024-11-18 21:20:52,365 DEBUG 2024-11-18 21:20:52,366 INFO 2024-11-18 21:20:52,366 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-140/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/stdout.txt 2024-11-18 21:20:52,366 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 21:20:53,701 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:53,701 DEBUG --- stdout --- 2024-11-18 21:20:53,702 DEBUG tar: Removing leading `/' from member names 2024-11-18 21:20:53,702 DEBUG --- stderr --- 2024-11-18 21:20:53,702 DEBUG ________________________________________________________________________________ [2024-11-18 21:20:53] benchmark-140 show_cmd : Display order command ________________________________________________________________________________ 2024-11-18 21:20:53,702 INFO 2024-11-18 21:20:53,702 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 21:20:53,702 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:53,979 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:53,979 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:53,979 DEBUG --- stdout --- 2024-11-18 21:20:53,979 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:53,979 DEBUG --- stderr --- 2024-11-18 21:20:53,979 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 21:20:53,979 INFO 2024-11-18 21:20:53,979 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 21:20:53,979 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:54,251 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:54,251 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:54,251 DEBUG --- stdout --- 2024-11-18 21:20:54,252 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:54,252 DEBUG --- stderr --- 2024-11-18 21:20:54,252 DEBUG 2024-11-18 21:20:54,252 INFO 2024-11-18 21:20:54,252 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-140/cmd.txt" ]; then echo "/results/orders/benchmark-140/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-140/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-140/cmd.txt file found" 2024-11-18 21:20:54,252 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 21:20:55,031 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 21:20:55,031 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:55,031 DEBUG --- stdout --- 2024-11-18 21:20:55,031 DEBUG /results/orders/benchmark-140/cmd.txt file found 2024-11-18 21:20:55,031 DEBUG --- stderr --- 2024-11-18 21:20:55,031 DEBUG 2024-11-18 21:20:55,031 INFO 2024-11-18 21:20:55,031 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 21:20:55,031 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:55,306 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:55,306 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:55,306 DEBUG --- stdout --- 2024-11-18 21:20:55,306 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:55,306 DEBUG --- stderr --- 2024-11-18 21:20:55,306 DEBUG 2024-11-18 21:20:55,306 INFO 2024-11-18 21:20:55,307 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-140/cmd.txt 2024-11-18 21:20:56,092 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 21:20:56,092 DEBUG --- stdout --- 2024-11-18 21:20:56,092 DEBUG 4 /results/orders/benchmark-140/cmd.txt 2024-11-18 21:20:56,092 DEBUG --- stderr --- 2024-11-18 21:20:56,093 DEBUG 2024-11-18 21:20:56,093 INFO - Download file (size 4k) 2024-11-18 21:20:56,093 INFO 2024-11-18 21:20:56,093 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 21:20:56,093 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:56,365 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:56,365 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:56,365 DEBUG --- stdout --- 2024-11-18 21:20:56,365 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:56,365 DEBUG --- stderr --- 2024-11-18 21:20:56,365 DEBUG source : /results/orders/benchmark-140/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/cmd.txt 2024-11-18 21:20:56,365 INFO 2024-11-18 21:20:56,365 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 21:20:56,365 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:56,638 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:56,639 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:56,639 DEBUG --- stdout --- 2024-11-18 21:20:56,639 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:56,639 DEBUG --- stderr --- 2024-11-18 21:20:56,639 DEBUG 2024-11-18 21:20:56,639 INFO 2024-11-18 21:20:56,639 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-140/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/cmd.txt 2024-11-18 21:20:56,639 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 21:20:57,342 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:57,342 DEBUG --- stdout --- 2024-11-18 21:20:57,342 DEBUG tar: Removing leading `/' from member names 2024-11-18 21:20:57,342 DEBUG --- stderr --- 2024-11-18 21:20:57,342 DEBUG 2024-11-18 21:20:57,342 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/cmd.txt is small (1 lines), printing all lines: 2024-11-18 21:20:57,342 DEBUG ----- output ----- 2024-11-18 21:20:57,342 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-140" --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=self1r460- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=140 -Duser_password=Pa_ssw0rd" 2024-11-18 21:20:57,342 INFO ________________________________________________________________________________ [2024-11-18 21:20:57] benchmark-140 show_rc : Display rc order ________________________________________________________________________________ 2024-11-18 21:20:57,342 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/rc.txt is small (1 lines), printing all lines: 2024-11-18 21:20:57,342 DEBUG ----- output ----- 2024-11-18 21:20:57,342 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-18 21:20:57,342 INFO ________________________________________________________________________________ [2024-11-18 21:20:57] benchmark-140 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-18 21:20:57,354 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/05_benchmark-140/stdout.txt is 94005 lines): 2024-11-18 21:20:57,354 DEBUG ----- output ----- 2024-11-18 21:20:57,354 INFO 2024-11-18 20:20:20,652 DEBUG Creating results folder /results/gatling/benchmark-140 2024-11-18 21:20:57,354 INFO 2024-11-18 20:20:20,660 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp7mc7y61a 2024-11-18 21:20:57,354 INFO 2024-11-18 20:20:20,662 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-140" -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=self1r460- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=140 -Duser_password=**** 2024-11-18 21:20:57,354 INFO [INFO] Scanning for projects... 2024-11-18 21:20:57,354 INFO [INFO] 2024-11-18 21:20:57,354 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-18 21:20:57,354 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-18 21:20:57,354 INFO [INFO] from pom.xml 2024-11-18 21:20:57,354 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-18 21:20:57,354 INFO [INFO] 2024-11-18 21:20:57,354 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-18 21:20:57,354 INFO [INFO] 2024-11-18 21:20:57,354 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-18 21:20:57,354 INFO [INFO] skip non existing resourceDirectory /tmp/tmp7mc7y61a/src/main/resources 2024-11-18 21:20:57,354 INFO [INFO] 2024-11-18 21:20:57,354 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-18 21:20:57,354 INFO [INFO] No sources to compile 2024-11-18 21:20:57,354 INFO [INFO] 2024-11-18 21:20:57,354 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-18 21:20:57,354 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-18 21:20:57,354 INFO [INFO] 2024-11-18 21:20:57,354 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-18 21:20:57,354 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-18 21:20:57,354 INFO [INFO] 2024-11-18 21:20:57,354 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-18 21:20:57,354 INFO [INFO] compiling 38 Scala sources to /tmp/tmp7mc7y61a/target/test-classes ... 2024-11-18 21:20:57,354 INFO [INFO] compile in 7.2 s 2024-11-18 21:20:57,354 INFO [INFO] 2024-11-18 21:20:57,354 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-18 21:20:57,354 INFO [INFO] 2024-11-18 21:20:57,354 INFO [INFO] 2024-11-18 21:20:57,354 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-18 21:20:57,354 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-18 21:20:57,355 INFO Gatling 3.13.1 is available! (you're using 3.12.0) 2024-11-18 21:20:57,355 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-18 21:20:57,355 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-18 21:20:57,355 INFO 20:20:32.920 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 21:20:57,355 INFO 20:20:34.328 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 21:20:57,355 INFO 20:20:35.283 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 21:20:57,355 INFO 20:20:35.783 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 21:20:57,355 INFO 20:20:35.840 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 21:20:57,355 INFO 20:20:36.096 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 21:20:57,355 INFO 20:20:36.385 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 21:20:57,355 INFO 20:20:36.714 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 21:20:57,355 INFO 2024-11-18 21:20:57,355 INFO ================================================================================ 2024-11-18 21:20:57,355 INFO 2024-11-18 20:20:36 GMT 5s elapsed 2024-11-18 21:20:57,355 INFO ---- Requests ------------------------------------------------------------------ 2024-11-18 21:20:57,355 INFO > Global (OK=414 KO=0 ) 2024-11-18 21:20:57,355 INFO > restLoginInitiate (OK=54 KO=0 ) 2024-11-18 21:20:57,355 INFO > restLoginUsernamePasswordCallback (OK=52 KO=0 ) 2024-11-18 21:20:57,355 INFO > Skip 2FA (OK=31 KO=0 ) 2024-11-18 21:20:57,355 INFO > User Authorize PKCE stage (OK=48 KO=0 ) 2024-11-18 21:20:57,355 INFO > User AccessToken PKCE stage (OK=46 KO=0 ) 2024-11-18 21:20:57,355 INFO > RootUserinfo (OK=20 KO=0 ) 2024-11-18 21:20:57,355 INFO > GetUUid (OK=16 KO=0 ) 2024-11-18 21:20:57,355 INFO > GetOpenidConfig (OK=16 KO=0 ) 2024-11-18 21:20:57,355 INFO > CreatePolicy (OK=16 KO=0 ) 2024-11-18 21:20:57,355 INFO > loginTreeWorkflow_restLoginInitiate (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > getIdToken (OK=1 KO=0 ) 2024-11-18 21:20:57,355 INFO > getIdToken Redirect 1 (OK=1 KO=0 ) 2024-11-18 21:20:57,355 INFO > createOrganization (OK=1 KO=0 ) 2024-11-18 21:20:57,355 INFO > queryOrganization (OK=1 KO=0 ) 2024-11-18 21:20:57,355 INFO > tokenUserinfo (OK=8 KO=0 ) 2024-11-18 21:20:57,355 INFO > deleteOrganization (OK=1 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI-Post-Login - stage 1 (OK=5 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=5 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI AM idFromSession (OK=5 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI AM validateGoto (OK=5 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI-Post-Login - stage 2 (OK=5 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=5 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI Enduser appAuthHelper1 (OK=5 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI Enduser appAuthHelper2 (OK=5 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI-Post-Login - stage 3 (OK=4 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI IDM uiconfig (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI Enduser sessioncheck (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI IDM version (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI IDM features (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI IDM privilege (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI IDM login (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI IDM managed user schema (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI IDM dashboard (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI IDM user (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI IDM oidcToken (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > userinfo (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI-Logout (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI-Logout Redirect 1 (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI AM endsession (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI AM revoke1 (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > UI AM revoke2 (OK=3 KO=0 ) 2024-11-18 21:20:57,355 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-11-18 21:20:57,355 INFO ---- Errors -------------------------------------------------------------------- 2024-11-18 21:20:57,355 INFO > tokenUserinfo: No attribute named 'access_token' is defined 8 (100.0%) 2024-11-18 21:20:57,355 INFO 2024-11-18 21:20:57,355 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-18 21:20:57,356 INFO [- ] 0% 2024-11-18 21:20:57,356 INFO waiting: 505964 / active: 34 / done: 24 2024-11-18 21:20:57,356 INFO ================================================================================ 2024-11-18 21:20:57,356 INFO [...] 2024-11-18 21:20:57,370 INFO Session: 2024-11-18 21:20:57,370 INFO Session(idc.benchmark.Mix2024,501794,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, username -> user.7404, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@d1cda85, 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, password -> Pa_ssw0rd, fake-ip -> 217.46.40.101, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@6bec356b),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x0000790648699970@7dc55856,io.netty.channel.epoll.EpollEventLoop@14f5da2c) 2024-11-18 21:20:57,370 INFO ========================= 2024-11-18 21:20:57,370 INFO HTTP request: 2024-11-18 21:20:57,370 INFO POST https://openam-perf-benchmark.forgeblocks.com/am/json/realms/root/realms/alpha/authenticate 2024-11-18 21:20:57,370 INFO headers: 2024-11-18 21:20:57,370 INFO accept: application/json 2024-11-18 21:20:57,370 INFO content-type: application/json 2024-11-18 21:20:57,370 INFO 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 2024-11-18 21:20:57,370 INFO X-Forwarded-For: 217.46.40.101 2024-11-18 21:20:57,370 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 21:20:57,370 INFO body:StringChunksRequestBody{charset=UTF-8, content=} 2024-11-18 21:20:57,370 INFO ========================= 2024-11-18 21:20:57,370 INFO HTTP response: 2024-11-18 21:20:57,370 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 21:20:57,370 INFO 21:20:31.744 [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 21:20:57,370 INFO 21:20:31.745 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM privilege' failed for user 501676: j.n.c.ClosedChannelException 2024-11-18 21:20:57,370 INFO 21:20:31.745 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 21:20:57,370 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 21:20:57,370 INFO Request: 2024-11-18 21:20:57,370 INFO UI IDM privilege: KO j.n.c.ClosedChannelException 2024-11-18 21:20:57,370 INFO ========================= 2024-11-18 21:20:57,370 INFO Session: 2024-11-18 21:20:57,371 INFO Session(idc.benchmark.Mix2024,501676,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE0MDgzMDIwNSIsInN1Ym5hbWUiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiI0Z1RnQUdBV0VxMm93eGFsdDZFclYwSDVLVk0iLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjQ4MzEsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjQ4MzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY4NDMxLCJpYXQiOjE3MzE5NjQ4MzEsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6InV3ODhPQTY0d1BCWHA5VEhuX28xQWxHaENUVSJ9.DwpdBRYK4YmngVCy8LyieVM8S6o_T4A8GUvDFF3amVY, username -> user.59405, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE0MDgzMTcxOSIsInN1Ym5hbWUiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiI2dUVZc29PRUlzaDR1dFhEWU5faENiZWVfZWsiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjQ4MzEsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjQ4MzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY4NDMxLCJpYXQiOjE3MzE5NjQ4MzEsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6Imk4dnhJa0dfQXk5ZWxab3Z3R3FqVTRITzFFUSJ9.x7jmEZBAGXroDd7xkVeS3RwiQlBUceoICVmNiwdaAUk, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@789bd52a, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> q6cHMKtjTm6agZSO6WiPvbPyLpA, callbacks -> {"tokenId":"PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731964831042), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731964831042))), user_uuid -> 95812ac5-ba46-4a1f-a25a-e087c67813f7, password -> Pa_ssw0rd, challenge -> i13WpmM0vuMzcIcGlqEMkp9YyLvWDde78N6KTbd2oFQ, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 183.220.0.202, verifier -> MTcwMzYyNTc1NjY5MzI3NTA4Nw, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@744c7194, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@6f73dd6e, tokenId -> PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (iPhone; CPU iPhone OS 13_2_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/13.0.3 Mobile/15E148 Safari/604.1),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x0000790648699970@7dc55856,io.netty.channel.epoll.EpollEventLoop@30f5a68a) 2024-11-18 21:20:57,371 INFO ========================= 2024-11-18 21:20:57,371 INFO HTTP request: 2024-11-18 21:20:57,371 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/privilege?_action=listPrivileges 2024-11-18 21:20:57,371 INFO headers: 2024-11-18 21:20:57,371 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 21:20:57,371 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 21:20:57,371 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE0MDgzMTcxOSIsInN1Ym5hbWUiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiI2dUVZc29PRUlzaDR1dFhEWU5faENiZWVfZWsiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjQ4MzEsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjQ4MzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY4NDMxLCJpYXQiOjE3MzE5NjQ4MzEsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6Imk4dnhJa0dfQXk5ZWxab3Z3R3FqVTRITzFFUSJ9.x7jmEZBAGXroDd7xkVeS3RwiQlBUceoICVmNiwdaAUk 2024-11-18 21:20:57,371 INFO Accept-Api-Version: 2024-11-18 21:20:57,371 INFO accept: */* 2024-11-18 21:20:57,371 INFO cookie: amlbcookie=01; 9b75a55744995d0=PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 21:20:57,371 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-18 21:20:57,371 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 21:20:57,371 INFO cookies: 2024-11-18 21:20:57,371 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 21:20:57,371 INFO 9b75a55744995d0=PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 21:20:57,371 INFO ========================= 2024-11-18 21:20:57,371 INFO HTTP response: 2024-11-18 21:20:57,371 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 21:20:57,371 INFO 21:20:31.745 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM version' failed for user 501676: j.n.c.ClosedChannelException 2024-11-18 21:20:57,371 INFO 21:20:31.745 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 21:20:57,371 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 21:20:57,371 INFO Request: 2024-11-18 21:20:57,371 INFO UI IDM version: KO j.n.c.ClosedChannelException 2024-11-18 21:20:57,371 INFO ========================= 2024-11-18 21:20:57,371 INFO Session: 2024-11-18 21:20:57,371 INFO Session(idc.benchmark.Mix2024,501676,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE0MDgzMDIwNSIsInN1Ym5hbWUiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiI0Z1RnQUdBV0VxMm93eGFsdDZFclYwSDVLVk0iLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjQ4MzEsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjQ4MzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY4NDMxLCJpYXQiOjE3MzE5NjQ4MzEsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6InV3ODhPQTY0d1BCWHA5VEhuX28xQWxHaENUVSJ9.DwpdBRYK4YmngVCy8LyieVM8S6o_T4A8GUvDFF3amVY, username -> user.59405, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE0MDgzMTcxOSIsInN1Ym5hbWUiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiI2dUVZc29PRUlzaDR1dFhEWU5faENiZWVfZWsiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjQ4MzEsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjQ4MzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY4NDMxLCJpYXQiOjE3MzE5NjQ4MzEsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6Imk4dnhJa0dfQXk5ZWxab3Z3R3FqVTRITzFFUSJ9.x7jmEZBAGXroDd7xkVeS3RwiQlBUceoICVmNiwdaAUk, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@789bd52a, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> q6cHMKtjTm6agZSO6WiPvbPyLpA, callbacks -> {"tokenId":"PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731964831042), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731964831042))), user_uuid -> 95812ac5-ba46-4a1f-a25a-e087c67813f7, password -> Pa_ssw0rd, challenge -> i13WpmM0vuMzcIcGlqEMkp9YyLvWDde78N6KTbd2oFQ, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 183.220.0.202, verifier -> MTcwMzYyNTc1NjY5MzI3NTA4Nw, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@744c7194, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@6f73dd6e, tokenId -> PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (iPhone; CPU iPhone OS 13_2_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/13.0.3 Mobile/15E148 Safari/604.1),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x0000790648699970@7dc55856,io.netty.channel.epoll.EpollEventLoop@30f5a68a) 2024-11-18 21:20:57,371 INFO ========================= 2024-11-18 21:20:57,371 INFO HTTP request: 2024-11-18 21:20:57,371 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/version 2024-11-18 21:20:57,371 INFO headers: 2024-11-18 21:20:57,371 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 21:20:57,371 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 21:20:57,371 INFO Accept-Api-Version: 2024-11-18 21:20:57,371 INFO accept: */* 2024-11-18 21:20:57,371 INFO cookie: amlbcookie=01; 9b75a55744995d0=PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 21:20:57,371 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 21:20:57,371 INFO cookies: 2024-11-18 21:20:57,371 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 21:20:57,371 INFO 9b75a55744995d0=PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 21:20:57,371 INFO ========================= 2024-11-18 21:20:57,371 INFO HTTP response: 2024-11-18 21:20:57,371 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 21:20:57,371 INFO 21:20:31.745 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 501676: j.n.c.ClosedChannelException 2024-11-18 21:20:57,371 INFO 21:20:31.745 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 21:20:57,371 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 21:20:57,371 INFO Request: 2024-11-18 21:20:57,371 INFO UI IDM login: KO j.n.c.ClosedChannelException 2024-11-18 21:20:57,371 INFO ========================= 2024-11-18 21:20:57,371 INFO Session: 2024-11-18 21:20:57,371 INFO Session(idc.benchmark.Mix2024,501676,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE0MDgzMDIwNSIsInN1Ym5hbWUiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiI0Z1RnQUdBV0VxMm93eGFsdDZFclYwSDVLVk0iLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjQ4MzEsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjQ4MzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY4NDMxLCJpYXQiOjE3MzE5NjQ4MzEsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6InV3ODhPQTY0d1BCWHA5VEhuX28xQWxHaENUVSJ9.DwpdBRYK4YmngVCy8LyieVM8S6o_T4A8GUvDFF3amVY, username -> user.59405, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE0MDgzMTcxOSIsInN1Ym5hbWUiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiI2dUVZc29PRUlzaDR1dFhEWU5faENiZWVfZWsiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjQ4MzEsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjQ4MzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY4NDMxLCJpYXQiOjE3MzE5NjQ4MzEsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6Imk4dnhJa0dfQXk5ZWxab3Z3R3FqVTRITzFFUSJ9.x7jmEZBAGXroDd7xkVeS3RwiQlBUceoICVmNiwdaAUk, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@789bd52a, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> q6cHMKtjTm6agZSO6WiPvbPyLpA, callbacks -> {"tokenId":"PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731964831042), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731964831042))), user_uuid -> 95812ac5-ba46-4a1f-a25a-e087c67813f7, password -> Pa_ssw0rd, challenge -> i13WpmM0vuMzcIcGlqEMkp9YyLvWDde78N6KTbd2oFQ, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 183.220.0.202, verifier -> MTcwMzYyNTc1NjY5MzI3NTA4Nw, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@744c7194, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@6f73dd6e, tokenId -> PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (iPhone; CPU iPhone OS 13_2_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/13.0.3 Mobile/15E148 Safari/604.1),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x0000790648699970@7dc55856,io.netty.channel.epoll.EpollEventLoop@30f5a68a) 2024-11-18 21:20:57,371 INFO ========================= 2024-11-18 21:20:57,371 INFO HTTP request: 2024-11-18 21:20:57,371 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-18 21:20:57,371 INFO headers: 2024-11-18 21:20:57,371 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 21:20:57,371 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 21:20:57,371 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE0MDgzMTcxOSIsInN1Ym5hbWUiOiI5NTgxMmFjNS1iYTQ2LTRhMWYtYTI1YS1lMDg3YzY3ODEzZjciLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiI2dUVZc29PRUlzaDR1dFhEWU5faENiZWVfZWsiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjQ4MzEsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjQ4MzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY4NDMxLCJpYXQiOjE3MzE5NjQ4MzEsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6Imk4dnhJa0dfQXk5ZWxab3Z3R3FqVTRITzFFUSJ9.x7jmEZBAGXroDd7xkVeS3RwiQlBUceoICVmNiwdaAUk 2024-11-18 21:20:57,371 INFO Accept-Api-Version: 2024-11-18 21:20:57,371 INFO accept: */* 2024-11-18 21:20:57,371 INFO cookie: amlbcookie=01; 9b75a55744995d0=PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 21:20:57,371 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-18 21:20:57,372 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 21:20:57,372 INFO cookies: 2024-11-18 21:20:57,372 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 21:20:57,372 INFO 9b75a55744995d0=PJYYTVd1SS5QjZ2aYulGc30Ympg.*AAJTSQACMDIAAlNLABxPU3dHZkhUaWxwVVdiNWxaUHQ5UU1zdnFaYVE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 21:20:57,372 INFO ========================= 2024-11-18 21:20:57,372 INFO HTTP response: 2024-11-18 21:20:57,372 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 21:20:57,372 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 21:20:57,372 INFO [INFO] BUILD SUCCESS 2024-11-18 21:20:57,372 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 21:20:57,372 INFO [INFO] Total time: 01:00 h 2024-11-18 21:20:57,372 INFO [INFO] Finished at: 2024-11-18T21:20:31Z 2024-11-18 21:20:57,372 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 21:20:57,372 INFO 2024-11-18 21:20:31,892 INFO Gatling simulation run ended successfully 2024-11-18 21:20:57,372 INFO 2024-11-18 21:20:31,892 DEBUG No archive name provided. No archive will be created 2024-11-18 21:20:57,372 INFO ________________________________________________________________________________ [2024-11-18 21:20:57] benchmark-140 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-18 21:20:57,372 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-140/stderr.txt is small (0 lines), printing all lines: 2024-11-18 21:20:57,372 DEBUG ----- output ----- 2024-11-18 21:20:57,372 DEBUG 2024-11-18 21:20:57,372 INFO ________________________________________________________________________________ [2024-11-18 21:20:57] benchmark-140 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 21:20:57] benchmark-140 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-18 21:20:57] benchmark-140 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped