--Task-- name: benchmark-80 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates below loop: False interval: None dependencies: [] wait_for: ['benchmark-40'] preceding_task: benchmark-40 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 80} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-06 22:12:31] benchmark-80 pre : N/A ________________________________________________________________________________ 2024-11-06 22:12:31 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-06 22:12:31 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-80/stdout.txt Pod log : /results/orders/benchmark-80 2024-11-06 22:12:31,141 INFO 2024-11-06 22:12:31,141 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-06 22:12:31,141 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 22:12:31,416 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 22:12:31,416 INFO [loop_until]: OK (rc = 0) 2024-11-06 22:12:31,416 DEBUG --- stdout --- 2024-11-06 22:12:31,416 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 22:12:31,416 DEBUG --- stderr --- 2024-11-06 22:12:31,416 DEBUG ________________________________________________________________________________ [2024-11-06 22:12:31] benchmark-80 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-06 22:12:31,416 INFO 2024-11-06 22:12:31,416 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-06 22:12:31,416 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 22:12:31,759 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 22:12:31,759 INFO [loop_until]: OK (rc = 0) 2024-11-06 22:12:31,759 DEBUG --- stdout --- 2024-11-06 22:12:31,759 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 22:12:31,759 DEBUG --- stderr --- 2024-11-06 22:12:31,759 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-80" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=80 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-06 22:12:31] benchmark-80 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-06 22:12:31,760 INFO 2024-11-06 22:12:31,760 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-06 22:12:31,760 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 22:12:32,039 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 22:12:32,039 INFO [loop_until]: OK (rc = 0) 2024-11-06 22:12:32,039 DEBUG --- stdout --- 2024-11-06 22:12:32,040 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 22:12:32,040 DEBUG --- stderr --- 2024-11-06 22:12:32,040 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/order.json { "name": "benchmark-80", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-80\" --no-reports --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx4G\" --simulation-system-properties=\"-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=80 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-80" } ________________________________________________________________________________ [2024-11-06 22:12:32] benchmark-80 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-11-06 22:12:32,043 INFO 2024-11-06 22:12:32,043 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-06 22:12:32,043 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-06 22:12:32,261 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-06 22:12:32,261 INFO [loop_until]: OK (rc = 0) 2024-11-06 22:12:32,261 DEBUG --- stdout --- 2024-11-06 22:12:32,261 DEBUG WAITING 2024-11-06 22:12:32,261 DEBUG --- stderr --- 2024-11-06 22:12:32,261 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 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-11-06 22:12:32] benchmark-80 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-06 22:12:32,262 INFO 2024-11-06 22:12:32,262 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-06 22:12:32,262 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 22:12:32,536 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 22:12:32,536 INFO [loop_until]: OK (rc = 0) 2024-11-06 22:12:32,536 DEBUG --- stdout --- 2024-11-06 22:12:32,536 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 22:12:32,536 DEBUG --- stderr --- 2024-11-06 22:12:32,536 DEBUG 2024-11-06 22:12:32,536 INFO 2024-11-06 22:12:32,536 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/order.json overseer-0-6644bb6697-9gfkx:/results/orders/order.json 2024-11-06 22:12:32,536 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 22:12:33,883 INFO [loop_until]: OK (rc = 0) 2024-11-06 22:12:33,883 DEBUG --- stdout --- 2024-11-06 22:12:33,883 DEBUG 2024-11-06 22:12:33,883 DEBUG --- stderr --- 2024-11-06 22:12:33,883 DEBUG Order has been successfully forwarded to overseer 2024-11-06 22:12:33 - INFO: Check if Gatling simulation started successfully 2024-11-06 22:12:33,883 INFO 2024-11-06 22:12:33,883 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-06 22:12:33,883 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 22:12:34,158 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 22:12:34,159 INFO [loop_until]: OK (rc = 0) 2024-11-06 22:12:34,159 DEBUG --- stdout --- 2024-11-06 22:12:34,159 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 22:12:34,159 DEBUG --- stderr --- 2024-11-06 22:12:34,159 DEBUG 2024-11-06 22:12:34,159 INFO 2024-11-06 22:12:34,159 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx -- head --lines 5000 /results/orders/benchmark-80/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-06 22:12:34,159 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-06 22:12:34,887 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-06 22:12:40,621 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-06 22:12:46,356 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-06 22:12:46,356 INFO [loop_until]: OK (rc = 0) 2024-11-06 22:12:46,356 DEBUG --- stdout --- 2024-11-06 22:12:46,356 DEBUG 2024-11-06 22:12:34,544 DEBUG Creating results folder /results/gatling/benchmark-80 2024-11-06 22:12:34,551 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp3vox1ish 2024-11-06 22:12:34,553 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-80" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=80 -Duser_password=**** [INFO] Scanning for projects... [INFO] [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- [INFO] Building pyrock-gatling-simulation 3.12.0 [INFO] from pom.xml [INFO] --------------------------------[ jar ]--------------------------------- [INFO] [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> [INFO] [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- [INFO] skip non existing resourceDirectory /tmp/tmp3vox1ish/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/tmp3vox1ish/target/test-classes ... [INFO] compile in 6.9 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. 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-06 22:12:46,356 DEBUG --- stderr --- 2024-11-06 22:12:46,356 DEBUG ________________________________________________________________________________ [2024-11-06 22:12:46] benchmark-80 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m. Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry PASS : Order stopped by itself after 1h and before timeout (1h 12m) ________________________________________________________________________________ [2024-11-06 23:12:56] benchmark-80 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-06 23:12:56,666 INFO 2024-11-06 23:12:56,666 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-06 23:12:56,666 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:12:56,964 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:12:56,964 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:12:56,964 DEBUG --- stdout --- 2024-11-06 23:12:56,964 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:12:56,964 DEBUG --- stderr --- 2024-11-06 23:12:56,964 DEBUG 2024-11-06 23:12:56,964 INFO 2024-11-06 23:12:56,965 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx -- rm -f /results/orders/order.json 2024-11-06 23:12:56,965 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 23:12:57,747 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:12:57,747 DEBUG --- stdout --- 2024-11-06 23:12:57,747 DEBUG 2024-11-06 23:12:57,747 DEBUG --- stderr --- 2024-11-06 23:12:57,747 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-06 23:12:57] benchmark-80 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-06 23:12:57 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/order.json 2024-11-06 23:12:57,748 INFO 2024-11-06 23:12:57,748 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-06 23:12:57,748 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:12:58,025 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:12:58,025 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:12:58,026 DEBUG --- stdout --- 2024-11-06 23:12:58,026 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:12:58,026 DEBUG --- stderr --- 2024-11-06 23:12:58,026 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-06 23:12:58,026 INFO 2024-11-06 23:12:58,026 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-06 23:12:58,026 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:12:58,302 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:12:58,302 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:12:58,303 DEBUG --- stdout --- 2024-11-06 23:12:58,303 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:12:58,303 DEBUG --- stderr --- 2024-11-06 23:12:58,303 DEBUG 2024-11-06 23:12:58,303 INFO 2024-11-06 23:12:58,303 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-80/rc.txt" ]; then echo "/results/orders/benchmark-80/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-80/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-80/rc.txt file found" 2024-11-06 23:12:58,303 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 23:12:59,101 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-06 23:12:59,101 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:12:59,101 DEBUG --- stdout --- 2024-11-06 23:12:59,101 DEBUG /results/orders/benchmark-80/rc.txt file found 2024-11-06 23:12:59,101 DEBUG --- stderr --- 2024-11-06 23:12:59,101 DEBUG 2024-11-06 23:12:59,101 INFO 2024-11-06 23:12:59,101 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-06 23:12:59,101 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:12:59,380 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:12:59,380 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:12:59,380 DEBUG --- stdout --- 2024-11-06 23:12:59,380 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:12:59,380 DEBUG --- stderr --- 2024-11-06 23:12:59,380 DEBUG 2024-11-06 23:12:59,380 INFO 2024-11-06 23:12:59,380 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-80/rc.txt 2024-11-06 23:13:00,170 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-06 23:13:00,170 DEBUG --- stdout --- 2024-11-06 23:13:00,170 DEBUG 4 /results/orders/benchmark-80/rc.txt 2024-11-06 23:13:00,171 DEBUG --- stderr --- 2024-11-06 23:13:00,171 DEBUG 2024-11-06 23:13:00,171 INFO - Download file (size 4k) 2024-11-06 23:13:00,171 INFO 2024-11-06 23:13:00,171 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-06 23:13:00,171 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:00,448 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:00,448 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:00,448 DEBUG --- stdout --- 2024-11-06 23:13:00,448 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:00,448 DEBUG --- stderr --- 2024-11-06 23:13:00,448 DEBUG source : /results/orders/benchmark-80/rc.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/rc.txt 2024-11-06 23:13:00,449 INFO 2024-11-06 23:13:00,449 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-06 23:13:00,449 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:00,725 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:00,725 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:00,725 DEBUG --- stdout --- 2024-11-06 23:13:00,725 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:00,725 DEBUG --- stderr --- 2024-11-06 23:13:00,725 DEBUG 2024-11-06 23:13:00,726 INFO 2024-11-06 23:13:00,726 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-80/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/rc.txt 2024-11-06 23:13:00,726 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-06 23:13:01,484 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:01,484 DEBUG --- stdout --- 2024-11-06 23:13:01,484 DEBUG tar: Removing leading `/' from member names 2024-11-06 23:13:01,484 DEBUG --- stderr --- 2024-11-06 23:13:01,484 DEBUG 2024-11-06 23:13:01,485 INFO 2024-11-06 23:13:01,485 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-06 23:13:01,485 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:01,765 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:01,765 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:01,765 DEBUG --- stdout --- 2024-11-06 23:13:01,765 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:01,765 DEBUG --- stderr --- 2024-11-06 23:13:01,765 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-06 23:13:01,765 INFO 2024-11-06 23:13:01,765 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-06 23:13:01,765 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:02,049 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:02,049 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:02,049 DEBUG --- stdout --- 2024-11-06 23:13:02,049 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:02,049 DEBUG --- stderr --- 2024-11-06 23:13:02,049 DEBUG 2024-11-06 23:13:02,050 INFO 2024-11-06 23:13:02,050 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-80/stderr.txt" ]; then echo "/results/orders/benchmark-80/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-80/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-80/stderr.txt file found" 2024-11-06 23:13:02,050 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 23:13:02,857 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-06 23:13:02,857 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:02,857 DEBUG --- stdout --- 2024-11-06 23:13:02,857 DEBUG /results/orders/benchmark-80/stderr.txt file found 2024-11-06 23:13:02,857 DEBUG --- stderr --- 2024-11-06 23:13:02,857 DEBUG 2024-11-06 23:13:02,857 INFO 2024-11-06 23:13:02,857 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 23:13:02,858 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:03,135 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:03,135 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:03,135 DEBUG --- stdout --- 2024-11-06 23:13:03,136 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:03,136 DEBUG --- stderr --- 2024-11-06 23:13:03,136 DEBUG 2024-11-06 23:13:03,136 INFO 2024-11-06 23:13:03,136 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-80/stderr.txt 2024-11-06 23:13:03,930 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-06 23:13:03,930 DEBUG --- stdout --- 2024-11-06 23:13:03,930 DEBUG 0 /results/orders/benchmark-80/stderr.txt 2024-11-06 23:13:03,930 DEBUG --- stderr --- 2024-11-06 23:13:03,930 DEBUG 2024-11-06 23:13:03,930 INFO - Download file (size 0k) 2024-11-06 23:13:03,930 INFO 2024-11-06 23:13:03,930 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-06 23:13:03,930 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:04,207 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:04,207 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:04,207 DEBUG --- stdout --- 2024-11-06 23:13:04,207 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:04,207 DEBUG --- stderr --- 2024-11-06 23:13:04,207 DEBUG source : /results/orders/benchmark-80/stderr.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stderr.txt 2024-11-06 23:13:04,208 INFO 2024-11-06 23:13:04,208 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-06 23:13:04,208 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:04,485 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:04,485 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:04,485 DEBUG --- stdout --- 2024-11-06 23:13:04,485 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:04,485 DEBUG --- stderr --- 2024-11-06 23:13:04,485 DEBUG 2024-11-06 23:13:04,486 INFO 2024-11-06 23:13:04,486 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-80/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stderr.txt 2024-11-06 23:13:04,486 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-06 23:13:05,199 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:05,199 DEBUG --- stdout --- 2024-11-06 23:13:05,199 DEBUG tar: Removing leading `/' from member names 2024-11-06 23:13:05,200 DEBUG --- stderr --- 2024-11-06 23:13:05,200 DEBUG 2024-11-06 23:13:05,200 INFO 2024-11-06 23:13:05,200 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-06 23:13:05,200 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:05,477 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:05,477 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:05,477 DEBUG --- stdout --- 2024-11-06 23:13:05,477 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:05,477 DEBUG --- stderr --- 2024-11-06 23:13:05,477 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-06 23:13:05,477 INFO 2024-11-06 23:13:05,477 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-06 23:13:05,478 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:05,754 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:05,754 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:05,754 DEBUG --- stdout --- 2024-11-06 23:13:05,754 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:05,754 DEBUG --- stderr --- 2024-11-06 23:13:05,754 DEBUG 2024-11-06 23:13:05,755 INFO 2024-11-06 23:13:05,755 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-80/stdout.txt" ]; then echo "/results/orders/benchmark-80/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-80/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-80/stdout.txt file found" 2024-11-06 23:13:05,755 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 23:13:06,550 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-06 23:13:06,550 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:06,550 DEBUG --- stdout --- 2024-11-06 23:13:06,550 DEBUG /results/orders/benchmark-80/stdout.txt file found 2024-11-06 23:13:06,550 DEBUG --- stderr --- 2024-11-06 23:13:06,550 DEBUG 2024-11-06 23:13:06,550 INFO 2024-11-06 23:13:06,550 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-06 23:13:06,550 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:06,826 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:06,826 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:06,826 DEBUG --- stdout --- 2024-11-06 23:13:06,827 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:06,827 DEBUG --- stderr --- 2024-11-06 23:13:06,827 DEBUG 2024-11-06 23:13:06,827 INFO 2024-11-06 23:13:06,827 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-80/stdout.txt 2024-11-06 23:13:07,615 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-06 23:13:07,615 DEBUG --- stdout --- 2024-11-06 23:13:07,615 DEBUG 4116 /results/orders/benchmark-80/stdout.txt 2024-11-06 23:13:07,615 DEBUG --- stderr --- 2024-11-06 23:13:07,615 DEBUG 2024-11-06 23:13:07,615 INFO - Download file (size 4116k) 2024-11-06 23:13:07,616 INFO 2024-11-06 23:13:07,616 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-06 23:13:07,616 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:07,893 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:07,893 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:07,893 DEBUG --- stdout --- 2024-11-06 23:13:07,893 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:07,893 DEBUG --- stderr --- 2024-11-06 23:13:07,893 DEBUG source : /results/orders/benchmark-80/stdout.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stdout.txt 2024-11-06 23:13:07,893 INFO 2024-11-06 23:13:07,893 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-06 23:13:07,893 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:08,171 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:08,172 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:08,172 DEBUG --- stdout --- 2024-11-06 23:13:08,172 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:08,172 DEBUG --- stderr --- 2024-11-06 23:13:08,172 DEBUG 2024-11-06 23:13:08,172 INFO 2024-11-06 23:13:08,172 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-80/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stdout.txt 2024-11-06 23:13:08,172 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-06 23:13:09,398 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:09,398 DEBUG --- stdout --- 2024-11-06 23:13:09,399 DEBUG tar: Removing leading `/' from member names 2024-11-06 23:13:09,399 DEBUG --- stderr --- 2024-11-06 23:13:09,399 DEBUG ________________________________________________________________________________ [2024-11-06 23:13:09] benchmark-80 show_cmd : Display order command ________________________________________________________________________________ 2024-11-06 23:13:09,399 INFO 2024-11-06 23:13:09,399 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-06 23:13:09,399 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:09,679 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:09,679 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:09,679 DEBUG --- stdout --- 2024-11-06 23:13:09,679 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:09,679 DEBUG --- stderr --- 2024-11-06 23:13:09,679 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-06 23:13:09,679 INFO 2024-11-06 23:13:09,679 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-06 23:13:09,679 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:09,953 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:09,953 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:09,953 DEBUG --- stdout --- 2024-11-06 23:13:09,953 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:09,953 DEBUG --- stderr --- 2024-11-06 23:13:09,953 DEBUG 2024-11-06 23:13:09,954 INFO 2024-11-06 23:13:09,954 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-80/cmd.txt" ]; then echo "/results/orders/benchmark-80/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-80/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-80/cmd.txt file found" 2024-11-06 23:13:09,954 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 23:13:10,745 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-06 23:13:10,745 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:10,745 DEBUG --- stdout --- 2024-11-06 23:13:10,745 DEBUG /results/orders/benchmark-80/cmd.txt file found 2024-11-06 23:13:10,745 DEBUG --- stderr --- 2024-11-06 23:13:10,745 DEBUG 2024-11-06 23:13:10,746 INFO 2024-11-06 23:13:10,746 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-06 23:13:10,746 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:11,021 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:11,021 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:11,021 DEBUG --- stdout --- 2024-11-06 23:13:11,021 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:11,021 DEBUG --- stderr --- 2024-11-06 23:13:11,021 DEBUG 2024-11-06 23:13:11,022 INFO 2024-11-06 23:13:11,022 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-80/cmd.txt 2024-11-06 23:13:11,815 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-06 23:13:11,816 DEBUG --- stdout --- 2024-11-06 23:13:11,816 DEBUG 4 /results/orders/benchmark-80/cmd.txt 2024-11-06 23:13:11,816 DEBUG --- stderr --- 2024-11-06 23:13:11,816 DEBUG 2024-11-06 23:13:11,816 INFO - Download file (size 4k) 2024-11-06 23:13:11,816 INFO 2024-11-06 23:13:11,816 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 23:13:11,816 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:12,093 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:12,093 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:12,093 DEBUG --- stdout --- 2024-11-06 23:13:12,093 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:12,093 DEBUG --- stderr --- 2024-11-06 23:13:12,093 DEBUG source : /results/orders/benchmark-80/cmd.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/cmd.txt 2024-11-06 23:13:12,093 INFO 2024-11-06 23:13:12,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-06 23:13:12,093 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:12,369 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:12,369 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:12,369 DEBUG --- stdout --- 2024-11-06 23:13:12,369 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:12,369 DEBUG --- stderr --- 2024-11-06 23:13:12,369 DEBUG 2024-11-06 23:13:12,370 INFO 2024-11-06 23:13:12,370 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-80/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/cmd.txt 2024-11-06 23:13:12,370 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-06 23:13:13,127 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:13,127 DEBUG --- stdout --- 2024-11-06 23:13:13,127 DEBUG tar: Removing leading `/' from member names 2024-11-06 23:13:13,127 DEBUG --- stderr --- 2024-11-06 23:13:13,128 DEBUG 2024-11-06 23:13:13,128 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/cmd.txt is small (1 lines), printing all lines: 2024-11-06 23:13:13,128 DEBUG ----- output ----- 2024-11-06 23:13:13,128 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-80" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=80 -Duser_password=Pa_ssw0rd" 2024-11-06 23:13:13,128 INFO ________________________________________________________________________________ [2024-11-06 23:13:13] benchmark-80 show_rc : Display rc order ________________________________________________________________________________ 2024-11-06 23:13:13,128 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/rc.txt is small (1 lines), printing all lines: 2024-11-06 23:13:13,128 DEBUG ----- output ----- 2024-11-06 23:13:13,128 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-06 23:13:13,128 INFO ________________________________________________________________________________ [2024-11-06 23:13:13] benchmark-80 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-06 23:13:13,134 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stdout.txt is 51956 lines): 2024-11-06 23:13:13,134 DEBUG ----- output ----- 2024-11-06 23:13:13,134 INFO 2024-11-06 22:12:34,544 DEBUG Creating results folder /results/gatling/benchmark-80 2024-11-06 23:13:13,134 INFO 2024-11-06 22:12:34,551 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp3vox1ish 2024-11-06 23:13:13,134 INFO 2024-11-06 22:12:34,553 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-80" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self80- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=80 -Duser_password=**** 2024-11-06 23:13:13,134 INFO [INFO] Scanning for projects... 2024-11-06 23:13:13,135 INFO [INFO] 2024-11-06 23:13:13,135 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-06 23:13:13,135 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-06 23:13:13,135 INFO [INFO] from pom.xml 2024-11-06 23:13:13,135 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-06 23:13:13,135 INFO [INFO] 2024-11-06 23:13:13,135 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-06 23:13:13,135 INFO [INFO] 2024-11-06 23:13:13,135 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-06 23:13:13,135 INFO [INFO] skip non existing resourceDirectory /tmp/tmp3vox1ish/src/main/resources 2024-11-06 23:13:13,135 INFO [INFO] 2024-11-06 23:13:13,135 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-06 23:13:13,135 INFO [INFO] No sources to compile 2024-11-06 23:13:13,135 INFO [INFO] 2024-11-06 23:13:13,135 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-06 23:13:13,135 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-06 23:13:13,135 INFO [INFO] 2024-11-06 23:13:13,135 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-06 23:13:13,135 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-06 23:13:13,135 INFO [INFO] 2024-11-06 23:13:13,135 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-06 23:13:13,135 INFO [INFO] compiling 38 Scala sources to /tmp/tmp3vox1ish/target/test-classes ... 2024-11-06 23:13:13,135 INFO [INFO] compile in 6.9 s 2024-11-06 23:13:13,135 INFO [INFO] 2024-11-06 23:13:13,135 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-06 23:13:13,135 INFO [INFO] 2024-11-06 23:13:13,135 INFO [INFO] 2024-11-06 23:13:13,135 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-06 23:13:13,135 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-06 23:13:13,135 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-06 23:13:13,135 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-06 23:13:13,135 INFO 2024-11-06 23:13:13,135 INFO ================================================================================ 2024-11-06 23:13:13,135 INFO 2024-11-06 22:12:50 GMT 5s elapsed 2024-11-06 23:13:13,135 INFO ---- Requests ------------------------------------------------------------------ 2024-11-06 23:13:13,135 INFO > Global (OK=424 KO=0 ) 2024-11-06 23:13:13,135 INFO > restLoginInitiate (OK=32 KO=0 ) 2024-11-06 23:13:13,135 INFO > restLoginUsernamePasswordCallback (OK=31 KO=0 ) 2024-11-06 23:13:13,135 INFO > Skip 2FA (OK=8 KO=0 ) 2024-11-06 23:13:13,135 INFO > User Authorize PKCE stage (OK=38 KO=0 ) 2024-11-06 23:13:13,135 INFO > User AccessToken PKCE stage (OK=37 KO=0 ) 2024-11-06 23:13:13,135 INFO > GetUUid (OK=6 KO=0 ) 2024-11-06 23:13:13,135 INFO > GetOpenidConfig (OK=6 KO=0 ) 2024-11-06 23:13:13,135 INFO > CreatePolicy (OK=6 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI-Post-Login - stage 1 (OK=12 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=12 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI AM idFromSession (OK=11 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI AM validateGoto (OK=11 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI-Post-Login - stage 2 (OK=10 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=9 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI Enduser appAuthHelper2 (OK=9 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI Enduser appAuthHelper1 (OK=9 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI-Post-Login - stage 3 (OK=9 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=9 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI Enduser sessioncheck (OK=9 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI IDM uiconfig (OK=9 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI IDM version (OK=9 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI IDM login (OK=9 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI IDM privilege (OK=9 KO=0 ) 2024-11-06 23:13:13,135 INFO > UI IDM features (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI IDM user (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI IDM dashboard (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI IDM managed user schema (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI IDM oidcToken (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > userinfo (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI-Logout (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI-Logout Redirect 1 (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > tokenUserinfo (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI AM revoke1 (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI AM revoke2 (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI AM endsession (OK=9 KO=0 ) 2024-11-06 23:13:13,136 INFO > RootUserinfo (OK=4 KO=0 ) 2024-11-06 23:13:13,136 INFO > loginTreeWorkflow_restLoginInitiate (OK=1 KO=0 ) 2024-11-06 23:13:13,136 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-11-06 23:13:13,136 INFO 2024-11-06 23:13:13,136 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-06 23:13:13,136 INFO [- ] 0% 2024-11-06 23:13:13,136 INFO waiting: 289129 / active: 14 / done: 19 2024-11-06 23:13:13,136 INFO ================================================================================ 2024-11-06 23:13:13,136 INFO 2024-11-06 23:13:13,136 INFO 2024-11-06 23:13:13,136 INFO ================================================================================ 2024-11-06 23:13:13,136 INFO 2024-11-06 22:12:55 GMT 10s elapsed 2024-11-06 23:13:13,136 INFO ---- Requests ------------------------------------------------------------------ 2024-11-06 23:13:13,136 INFO > Global (OK=1566 KO=0 ) 2024-11-06 23:13:13,136 INFO > restLoginInitiate (OK=125 KO=0 ) 2024-11-06 23:13:13,136 INFO > restLoginUsernamePasswordCallback (OK=123 KO=0 ) 2024-11-06 23:13:13,136 INFO > Skip 2FA (OK=64 KO=0 ) 2024-11-06 23:13:13,136 INFO > User Authorize PKCE stage (OK=147 KO=0 ) 2024-11-06 23:13:13,136 INFO > User AccessToken PKCE stage (OK=146 KO=0 ) 2024-11-06 23:13:13,136 INFO > GetUUid (OK=40 KO=0 ) 2024-11-06 23:13:13,136 INFO > GetOpenidConfig (OK=46 KO=0 ) 2024-11-06 23:13:13,136 INFO > CreatePolicy (OK=40 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI-Post-Login - stage 1 (OK=28 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=28 KO=0 ) 2024-11-06 23:13:13,136 INFO > UI AM idFromSession (OK=28 KO=0 ) 2024-11-06 23:13:13,136 INFO [...] 2024-11-06 23:13:13,147 INFO Session(idc.benchmark.Mix2024,286701,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, username -> user.686813, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@3cf1d887, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, callbacks -> {"tokenId":"VEv4O3CXkgO6WTpLOKuU5Ink2cM.*AAJTSQACMDIAAlNLABxZRWU0UzU5d2FMenpYbmprUDU3Rk5xanR4ZHc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1730934765297), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=VEv4O3CXkgO6WTpLOKuU5Ink2cM.*AAJTSQACMDIAAlNLABxZRWU0UzU5d2FMenpYbmprUDU3Rk5xanR4ZHc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1730934765297))), password -> Pa_ssw0rd, fake-ip -> 137.5.57.65, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@575f5c5b, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@75a45005, tokenId -> VEv4O3CXkgO6WTpLOKuU5Ink2cM.*AAJTSQACMDIAAlNLABxZRWU0UzU5d2FMenpYbmprUDU3Rk5xanR4ZHc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/64.0.3282.140 Safari/537.36 Edge/17.17134),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007f6bc869a0e0@448d403e,io.netty.channel.epoll.EpollEventLoop@1e1d3956) 2024-11-06 23:13:13,147 INFO ========================= 2024-11-06 23:13:13,147 INFO HTTP request: 2024-11-06 23:13:13,147 INFO POST https://openam-perf-benchmark.forgeblocks.com/am/json/realms/root/realms/alpha/users?_action=validateGoto 2024-11-06 23:13:13,147 INFO headers: 2024-11-06 23:13:13,147 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-06 23:13:13,147 INFO Accept-API-Version: resource=2.1, protocol=1.0 2024-11-06 23:13:13,147 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-06 23:13:13,147 INFO accept: */* 2024-11-06 23:13:13,147 INFO cookie: amlbcookie=01; 9b75a55744995d0=VEv4O3CXkgO6WTpLOKuU5Ink2cM.*AAJTSQACMDIAAlNLABxZRWU0UzU5d2FMenpYbmprUDU3Rk5xanR4ZHc9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-06 23:13:13,147 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-06 23:13:13,147 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-06 23:13:13,147 INFO content-length: 0 2024-11-06 23:13:13,147 INFO cookies: 2024-11-06 23:13:13,147 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-06 23:13:13,147 INFO 9b75a55744995d0=VEv4O3CXkgO6WTpLOKuU5Ink2cM.*AAJTSQACMDIAAlNLABxZRWU0UzU5d2FMenpYbmprUDU3Rk5xanR4ZHc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-06 23:13:13,147 INFO ========================= 2024-11-06 23:13:13,147 INFO HTTP response: 2024-11-06 23:13:13,147 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-06 23:13:13,147 INFO 23:12:45.428 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-06 23:13:13,147 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-06 23:13:13,147 INFO Request: 2024-11-06 23:13:13,147 INFO UI IDM version: KO j.i.IOException: Premature close 2024-11-06 23:13:13,147 INFO ========================= 2024-11-06 23:13:13,147 INFO Session: 2024-11-06 23:13:13,147 INFO Session(idc.benchmark.Mix2024,286665,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI2NjYyMTI4ZS05MjkxLTQ3MTMtYTAyYS0xMGUzM2I0YzQ2MzAiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMjgxZDgzZmMtYmFlNy00YjRhLWIwYWMtNWM0ZGQzMjg4ZGRlLTQyMDcwNjI0Iiwic3VibmFtZSI6IjY2NjIxMjhlLTkyOTEtNDcxMy1hMDJhLTEwZTMzYjRjNDYzMCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6Ilk2ZzlkNl81X1FSUjl0cENOVFo0MnRLNjRGUSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzNDc2NSwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzNDc2NCwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5MzgzNjUsImlhdCI6MTczMDkzNDc2NSwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoicEtld0tLVWNsTVFtakhqdHJ0RDJaLTBob0VvIn0.joLEe5Xo-FrZnFXmyXzxqY5tVHKuBs_XfZ4KI0bBVgg, username -> user.753927, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI2NjYyMTI4ZS05MjkxLTQ3MTMtYTAyYS0xMGUzM2I0YzQ2MzAiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMGU4MWU4NmQtODlhYy00YTliLTgwNDEtYzE5ZTVjY2JhOGRmLTQyMDA4OTM2Iiwic3VibmFtZSI6IjY2NjIxMjhlLTkyOTEtNDcxMy1hMDJhLTEwZTMzYjRjNDYzMCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImtiU2UtTjJHektsNkFJOFJnaEJXNHNqOHRETSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzNDc2NSwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzNDc2NCwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5MzgzNjUsImlhdCI6MTczMDkzNDc2NSwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiSUtxZG1pLVVVN0IxMVFxbl9wUU14dlc5NXlZIn0.PDj8nMJw9MRB79_r7eIjJ-9dZ6Gr1lJskWUTJ3XAuV8, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@52f2546, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> TH4qNojDWr27GtlM7jpZ9M-GKxU, callbacks -> {"tokenId":"_vL3kbWm7eRsmcTihZvnut9Bzw8.*AAJTSQACMDIAAlNLABxpa1lrdnRZdUdocWtvdTRtRnU1VFpXeSswY2s9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1730934764836), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=_vL3kbWm7eRsmcTihZvnut9Bzw8.*AAJTSQACMDIAAlNLABxpa1lrdnRZdUdocWtvdTRtRnU1VFpXeSswY2s9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1730934764836))), user_uuid -> 6662128e-9291-4713-a02a-10e33b4c4630, password -> Pa_ssw0rd, challenge -> LGznzqCtvNnz1nTDHHwggiJ6xzRfGo5LkOmffZLrROU, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 142.138.73.20, verifier -> NjUwMTMwMTQzMDA1MjQ0NDQ2MA, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@54e34947, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@2d0fe83f, tokenId -> _vL3kbWm7eRsmcTihZvnut9Bzw8.*AAJTSQACMDIAAlNLABxpa1lrdnRZdUdocWtvdTRtRnU1VFpXeSswY2s9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (iPhone; CPU iPhone OS 10_3 like Mac OS X) AppleWebKit/602.1.50 (KHTML, like Gecko) CriOS/56.0.2924.75 Mobile/14E5239e Safari/602.1),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007f6bc869a0e0@448d403e,io.netty.channel.epoll.EpollEventLoop@5b7ea70d) 2024-11-06 23:13:13,147 INFO ========================= 2024-11-06 23:13:13,147 INFO HTTP request: 2024-11-06 23:13:13,147 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/version 2024-11-06 23:13:13,147 INFO headers: 2024-11-06 23:13:13,147 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-06 23:13:13,147 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-06 23:13:13,147 INFO Accept-Api-Version: 2024-11-06 23:13:13,147 INFO accept: */* 2024-11-06 23:13:13,147 INFO cookie: amlbcookie=01; 9b75a55744995d0=_vL3kbWm7eRsmcTihZvnut9Bzw8.*AAJTSQACMDIAAlNLABxpa1lrdnRZdUdocWtvdTRtRnU1VFpXeSswY2s9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-06 23:13:13,147 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-06 23:13:13,147 INFO cookies: 2024-11-06 23:13:13,147 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-06 23:13:13,147 INFO 9b75a55744995d0=_vL3kbWm7eRsmcTihZvnut9Bzw8.*AAJTSQACMDIAAlNLABxpa1lrdnRZdUdocWtvdTRtRnU1VFpXeSswY2s9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-06 23:13:13,148 INFO ========================= 2024-11-06 23:13:13,148 INFO HTTP response: 2024-11-06 23:13:13,148 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-06 23:13:13,148 INFO 23:12:45.428 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM version' failed for user 286667: j.i.IOException: Premature close 2024-11-06 23:13:13,148 INFO 23:12:45.428 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-06 23:13:13,148 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-06 23:13:13,148 INFO Request: 2024-11-06 23:13:13,148 INFO UI IDM version: KO j.i.IOException: Premature close 2024-11-06 23:13:13,148 INFO ========================= 2024-11-06 23:13:13,148 INFO Session: 2024-11-06 23:13:13,148 INFO Session(idc.benchmark.Mix2024,286667,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIyZmUyNmVlMC00YjMzLTQ5MzQtODQwNi0xZmM0NjMxOTc5NzgiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMjkyNTgwZDctOWFkMi00MDljLTg4OGItNjYyYmRiNTE2MDQ2LTQyNTEzNjIwIiwic3VibmFtZSI6IjJmZTI2ZWUwLTRiMzMtNDkzNC04NDA2LTFmYzQ2MzE5Nzk3OCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6InFHa3pDSlo2ejQ2alQ2cEJsb1Zad2NxYk93cyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzNDc2NSwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzNDc2NCwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5MzgzNjUsImlhdCI6MTczMDkzNDc2NSwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiTG80TWY3Y2hIdHNWcmxjZnlVeHFadl9WZkhrIn0.BLqsEMaCQzoxZDeYkD5mANs_Oi4rrvUUrBKJgWCPus8, username -> user.957892, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIyZmUyNmVlMC00YjMzLTQ5MzQtODQwNi0xZmM0NjMxOTc5NzgiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMjkyNTgwZDctOWFkMi00MDljLTg4OGItNjYyYmRiNTE2MDQ2LTQyNTE0MzA4Iiwic3VibmFtZSI6IjJmZTI2ZWUwLTRiMzMtNDkzNC04NDA2LTFmYzQ2MzE5Nzk3OCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6Ink0NmJhNjhHakkwM243dUF4b0lSektoLXpGayIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzNDc2NSwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzNDc2NCwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5MzgzNjUsImlhdCI6MTczMDkzNDc2NSwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoidFItMlZjQjVNUXg1eDVWWGowQkJCdnNHb29rIn0.VxpMw955dhhJVS9LByFNIEfrD0J-OafyDvPoQiy4imk, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@2060144a, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> p2acWWkFgEQAfmWv2mW-KEGfdlg, callbacks -> {"tokenId":"NTz4VXCB7chiGpv8wOTX8EGz0bQ.*AAJTSQACMDIAAlNLABwwcDBEU3l6Y1pCNFdlWmdjUFJtbytqdjNDQnM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1730934764839), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=NTz4VXCB7chiGpv8wOTX8EGz0bQ.*AAJTSQACMDIAAlNLABwwcDBEU3l6Y1pCNFdlWmdjUFJtbytqdjNDQnM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1730934764839))), user_uuid -> 2fe26ee0-4b33-4934-8406-1fc463197978, password -> Pa_ssw0rd, challenge -> PbI2lXGKdqu-hmPzZT_g9ROlcunIcuuh7uai-l3_uQM, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 185.184.100.116, verifier -> LTE5NjY3NDk5MjExNDQxNzM3NTk, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@3b12ce16, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@a8735a0, tokenId -> NTz4VXCB7chiGpv8wOTX8EGz0bQ.*AAJTSQACMDIAAlNLABwwcDBEU3l6Y1pCNFdlWmdjUFJtbytqdjNDQnM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Linux; Android 6.0; Nexus 5 Build/MRA58N) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.85 Mobile Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007f6bc869a0e0@448d403e,io.netty.channel.epoll.EpollEventLoop@650eab8) 2024-11-06 23:13:13,148 INFO ========================= 2024-11-06 23:13:13,148 INFO HTTP request: 2024-11-06 23:13:13,148 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/version 2024-11-06 23:13:13,148 INFO headers: 2024-11-06 23:13:13,148 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-06 23:13:13,148 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-06 23:13:13,148 INFO Accept-Api-Version: 2024-11-06 23:13:13,148 INFO accept: */* 2024-11-06 23:13:13,148 INFO cookie: amlbcookie=01; 9b75a55744995d0=NTz4VXCB7chiGpv8wOTX8EGz0bQ.*AAJTSQACMDIAAlNLABwwcDBEU3l6Y1pCNFdlWmdjUFJtbytqdjNDQnM9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-06 23:13:13,148 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-06 23:13:13,148 INFO cookies: 2024-11-06 23:13:13,148 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-06 23:13:13,148 INFO 9b75a55744995d0=NTz4VXCB7chiGpv8wOTX8EGz0bQ.*AAJTSQACMDIAAlNLABwwcDBEU3l6Y1pCNFdlWmdjUFJtbytqdjNDQnM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-06 23:13:13,148 INFO ========================= 2024-11-06 23:13:13,148 INFO HTTP response: 2024-11-06 23:13:13,148 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-06 23:13:13,148 INFO 23:12:45.428 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM privilege' failed for user 286666: j.n.c.ClosedChannelException 2024-11-06 23:13:13,148 INFO 23:12:45.428 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-06 23:13:13,148 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-06 23:13:13,148 INFO Request: 2024-11-06 23:13:13,148 INFO UI IDM privilege: KO j.n.c.ClosedChannelException 2024-11-06 23:13:13,148 INFO ========================= 2024-11-06 23:13:13,148 INFO Session: 2024-11-06 23:13:13,148 INFO Session(idc.benchmark.Mix2024,286666,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI1OGY3MWEwNC1mNDMxLTQ4NjItOTMzYy1lODcwZDQ3MDc3YWQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMjkyNTgwZDctOWFkMi00MDljLTg4OGItNjYyYmRiNTE2MDQ2LTQyNTEzNjQ3Iiwic3VibmFtZSI6IjU4ZjcxYTA0LWY0MzEtNDg2Mi05MzNjLWU4NzBkNDcwNzdhZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6Ilgzb3dXNnpYZk1tWk9fNlhaa0xrRHNfdXUzMCIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzNDc2NSwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzNDc2NCwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5MzgzNjUsImlhdCI6MTczMDkzNDc2NSwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiRHhTT2d2czNNSURDcnR1blp3bU9GQ09BX0J3In0.FvWfFxfakbRXhAg9WlxnMw1RIHDTRLZTXW0C5vG4lC0, username -> user.936421, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI1OGY3MWEwNC1mNDMxLTQ4NjItOTMzYy1lODcwZDQ3MDc3YWQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMGU4MWU4NmQtODlhYy00YTliLTgwNDEtYzE5ZTVjY2JhOGRmLTQyMDA5MDI0Iiwic3VibmFtZSI6IjU4ZjcxYTA0LWY0MzEtNDg2Mi05MzNjLWU4NzBkNDcwNzdhZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6InBERVg0NG9JeE80bFIxWnRzZ01JNU9JMEJmayIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzNDc2NSwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzNDc2NCwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5MzgzNjUsImlhdCI6MTczMDkzNDc2NSwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiWUIzU1NacDR2ODM4cEJaWUJ0V3hxMTVKekRjIn0.ixkwSzXdquWtQ5ZdC7QieQkukDcv4vyymgBKpbQ_MN4, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@7a0a9454, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> 95wrdRc-qOgeSjZhwRTlhvZapKE, callbacks -> {"tokenId":"mFLddhEiWn34v49APAzAGbvMb1A.*AAJTSQACMDIAAlNLABxnTkVBcTlnTTdmRkNXWkdEYm5JTG9JQzlxbjA9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1730934764835), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=mFLddhEiWn34v49APAzAGbvMb1A.*AAJTSQACMDIAAlNLABxnTkVBcTlnTTdmRkNXWkdEYm5JTG9JQzlxbjA9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1730934764835))), user_uuid -> 58f71a04-f431-4862-933c-e870d47077ad, password -> Pa_ssw0rd, challenge -> TVdPQPBpA6glljqIfsNLvSFWMEnnDA6Nex6h1R-cVrc, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 63.124.90.57, verifier -> LTgxODA4NjI0MTAxMzgzNDQ2MzM, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@2cbfdc9b, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@51f2f116, tokenId -> mFLddhEiWn34v49APAzAGbvMb1A.*AAJTSQACMDIAAlNLABxnTkVBcTlnTTdmRkNXWkdEYm5JTG9JQzlxbjA9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_2) AppleWebKit/601.3.9 (KHTML, like Gecko) Version/9.0.2 Safari/601.3.9),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007f6bc869a0e0@448d403e,io.netty.channel.epoll.EpollEventLoop@2bef51f2) 2024-11-06 23:13:13,148 INFO ========================= 2024-11-06 23:13:13,148 INFO HTTP request: 2024-11-06 23:13:13,148 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/privilege?_action=listPrivileges 2024-11-06 23:13:13,148 INFO headers: 2024-11-06 23:13:13,148 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-06 23:13:13,148 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-06 23:13:13,148 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI1OGY3MWEwNC1mNDMxLTQ4NjItOTMzYy1lODcwZDQ3MDc3YWQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMGU4MWU4NmQtODlhYy00YTliLTgwNDEtYzE5ZTVjY2JhOGRmLTQyMDA5MDI0Iiwic3VibmFtZSI6IjU4ZjcxYTA0LWY0MzEtNDg2Mi05MzNjLWU4NzBkNDcwNzdhZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6InBERVg0NG9JeE80bFIxWnRzZ01JNU9JMEJmayIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzNDc2NSwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzNDc2NCwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5MzgzNjUsImlhdCI6MTczMDkzNDc2NSwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiWUIzU1NacDR2ODM4cEJaWUJ0V3hxMTVKekRjIn0.ixkwSzXdquWtQ5ZdC7QieQkukDcv4vyymgBKpbQ_MN4 2024-11-06 23:13:13,148 INFO Accept-Api-Version: 2024-11-06 23:13:13,148 INFO accept: */* 2024-11-06 23:13:13,148 INFO cookie: amlbcookie=01; 9b75a55744995d0=mFLddhEiWn34v49APAzAGbvMb1A.*AAJTSQACMDIAAlNLABxnTkVBcTlnTTdmRkNXWkdEYm5JTG9JQzlxbjA9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-06 23:13:13,148 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-06 23:13:13,148 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-06 23:13:13,148 INFO cookies: 2024-11-06 23:13:13,148 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-06 23:13:13,148 INFO 9b75a55744995d0=mFLddhEiWn34v49APAzAGbvMb1A.*AAJTSQACMDIAAlNLABxnTkVBcTlnTTdmRkNXWkdEYm5JTG9JQzlxbjA9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-06 23:13:13,148 INFO ========================= 2024-11-06 23:13:13,148 INFO HTTP response: 2024-11-06 23:13:13,148 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-06 23:13:13,148 INFO [INFO] ------------------------------------------------------------------------ 2024-11-06 23:13:13,148 INFO [INFO] BUILD SUCCESS 2024-11-06 23:13:13,148 INFO [INFO] ------------------------------------------------------------------------ 2024-11-06 23:13:13,148 INFO [INFO] Total time: 01:00 h 2024-11-06 23:13:13,148 INFO [INFO] Finished at: 2024-11-06T23:12:45Z 2024-11-06 23:13:13,149 INFO [INFO] ------------------------------------------------------------------------ 2024-11-06 23:13:13,149 INFO 2024-11-06 23:12:45,541 INFO Gatling simulation run ended successfully 2024-11-06 23:13:13,149 INFO 2024-11-06 23:12:45,541 DEBUG No archive name provided. No archive will be created 2024-11-06 23:13:13,149 INFO ________________________________________________________________________________ [2024-11-06 23:13:13] benchmark-80 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-06 23:13:13,149 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-80/stderr.txt is small (0 lines), printing all lines: 2024-11-06 23:13:13,149 DEBUG ----- output ----- 2024-11-06 23:13:13,149 DEBUG 2024-11-06 23:13:13,149 INFO ________________________________________________________________________________ [2024-11-06 23:13:13] benchmark-80 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-11-06 23:13:13] benchmark-80 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-06 23:13:13] benchmark-80 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped