--Task-- name: benchmark-60 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-50'] preceding_task: benchmark-50 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self60-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 60} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-13 08:36:45] benchmark-60 pre : N/A ________________________________________________________________________________ 2024-11-13 08:36:45 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-13 08:36:45 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-60/stdout.txt Pod log : /results/orders/benchmark-60 2024-11-13 08:36:45,648 INFO 2024-11-13 08:36:45,648 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-13 08:36:45,648 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:45,932 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:45,933 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:45,933 DEBUG --- stdout --- 2024-11-13 08:36:45,933 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:45,933 DEBUG --- stderr --- 2024-11-13 08:36:45,933 DEBUG ________________________________________________________________________________ [2024-11-13 08:36:45] benchmark-60 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-13 08:36:45,933 INFO 2024-11-13 08:36:45,933 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-13 08:36:45,933 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:46,221 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:46,221 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:46,221 DEBUG --- stdout --- 2024-11-13 08:36:46,221 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:46,221 DEBUG --- stderr --- 2024-11-13 08:36:46,221 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-60" --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=self60- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=60 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-13 08:36:46] benchmark-60 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-13 08:36:46,221 INFO 2024-11-13 08:36:46,221 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-13 08:36:46,221 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:46,508 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:46,508 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:46,508 DEBUG --- stdout --- 2024-11-13 08:36:46,508 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:46,508 DEBUG --- stderr --- 2024-11-13 08:36:46,508 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/order.json { "name": "benchmark-60", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-60\" --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=self60- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=60 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-60" } ________________________________________________________________________________ [2024-11-13 08:36:46] benchmark-60 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-13 08:36:46,511 INFO 2024-11-13 08:36:46,511 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-13 08:36:46,511 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-13 08:36:46,738 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 08:36:46,738 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:46,738 DEBUG --- stdout --- 2024-11-13 08:36:46,738 DEBUG WAITING 2024-11-13 08:36:46,738 DEBUG --- stderr --- 2024-11-13 08:36:46,739 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-11-13 08:36:46] benchmark-60 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-13 08:36:46,739 INFO 2024-11-13 08:36:46,739 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-13 08:36:46,739 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:47,037 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:47,037 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:47,037 DEBUG --- stdout --- 2024-11-13 08:36:47,037 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:47,037 DEBUG --- stderr --- 2024-11-13 08:36:47,037 DEBUG 2024-11-13 08:36:47,038 INFO 2024-11-13 08:36:47,038 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/order.json overseer-0-6644bb6697-rchxr:/results/orders/order.json 2024-11-13 08:36:47,038 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 08:36:48,454 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:48,454 DEBUG --- stdout --- 2024-11-13 08:36:48,454 DEBUG 2024-11-13 08:36:48,454 DEBUG --- stderr --- 2024-11-13 08:36:48,454 DEBUG Order has been successfully forwarded to overseer 2024-11-13 08:36:48 - INFO: Check if Gatling simulation started successfully 2024-11-13 08:36:48,454 INFO 2024-11-13 08:36:48,454 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-13 08:36:48,454 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:48,740 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:48,741 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:48,741 DEBUG --- stdout --- 2024-11-13 08:36:48,741 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:48,741 DEBUG --- stderr --- 2024-11-13 08:36:48,741 DEBUG 2024-11-13 08:36:48,741 INFO 2024-11-13 08:36:48,741 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-60/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-13 08:36:48,741 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-13 08:36:49,493 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-13 08:36:55,269 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-13 08:37:01,021 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-13 08:37:01,021 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:37:01,021 DEBUG --- stdout --- 2024-11-13 08:37:01,021 DEBUG 2024-11-13 08:36:49,424 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpj97_umwa 2024-11-13 08:36:49,426 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-60" -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=self60- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=60 -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/tmpj97_umwa/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/tmpj97_umwa/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-13 08:37:01,021 DEBUG --- stderr --- 2024-11-13 08:37:01,021 DEBUG ________________________________________________________________________________ [2024-11-13 08:37:01] benchmark-60 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m. Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 02s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 03s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 04s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 05s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 06s, retry Order is running since 46m 06s, retry Order is running since 48m 07s, retry Order is running since 50m 07s, retry Order is running since 52m 07s, retry Order is running since 54m 08s, 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-13 09:37:10] benchmark-60 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-13 09:37:10,181 INFO 2024-11-13 09:37:10,181 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-13 09:37:10,181 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:10,536 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:10,536 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:10,536 DEBUG --- stdout --- 2024-11-13 09:37:10,536 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:10,536 DEBUG --- stderr --- 2024-11-13 09:37:10,536 DEBUG 2024-11-13 09:37:10,537 INFO 2024-11-13 09:37:10,537 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-13 09:37:10,537 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 09:37:11,344 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:11,344 DEBUG --- stdout --- 2024-11-13 09:37:11,344 DEBUG 2024-11-13 09:37:11,344 DEBUG --- stderr --- 2024-11-13 09:37:11,344 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-13 09:37:11] benchmark-60 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-13 09:37:11 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/order.json 2024-11-13 09:37:11,345 INFO 2024-11-13 09:37:11,345 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-13 09:37:11,345 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:11,629 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:11,629 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:11,629 DEBUG --- stdout --- 2024-11-13 09:37:11,630 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:11,630 DEBUG --- stderr --- 2024-11-13 09:37:11,630 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 09:37:11,630 INFO 2024-11-13 09:37:11,630 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-13 09:37:11,630 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:11,916 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:11,916 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:11,916 DEBUG --- stdout --- 2024-11-13 09:37:11,916 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:11,916 DEBUG --- stderr --- 2024-11-13 09:37:11,916 DEBUG 2024-11-13 09:37:11,917 INFO 2024-11-13 09:37:11,917 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-60/rc.txt" ]; then echo "/results/orders/benchmark-60/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-60/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-60/rc.txt file found" 2024-11-13 09:37:11,917 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 09:37:12,739 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 09:37:12,739 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:12,739 DEBUG --- stdout --- 2024-11-13 09:37:12,739 DEBUG /results/orders/benchmark-60/rc.txt file found 2024-11-13 09:37:12,739 DEBUG --- stderr --- 2024-11-13 09:37:12,739 DEBUG 2024-11-13 09:37:12,739 INFO 2024-11-13 09:37:12,739 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-13 09:37:12,739 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:13,024 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:13,024 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:13,024 DEBUG --- stdout --- 2024-11-13 09:37:13,024 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:13,024 DEBUG --- stderr --- 2024-11-13 09:37:13,024 DEBUG 2024-11-13 09:37:13,024 INFO 2024-11-13 09:37:13,024 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-60/rc.txt 2024-11-13 09:37:13,853 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 09:37:13,853 DEBUG --- stdout --- 2024-11-13 09:37:13,853 DEBUG 4 /results/orders/benchmark-60/rc.txt 2024-11-13 09:37:13,853 DEBUG --- stderr --- 2024-11-13 09:37:13,853 DEBUG 2024-11-13 09:37:13,853 INFO - Download file (size 4k) 2024-11-13 09:37:13,853 INFO 2024-11-13 09:37:13,853 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-13 09:37:13,853 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:14,138 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:14,139 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:14,139 DEBUG --- stdout --- 2024-11-13 09:37:14,139 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:14,139 DEBUG --- stderr --- 2024-11-13 09:37:14,139 DEBUG source : /results/orders/benchmark-60/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/rc.txt 2024-11-13 09:37:14,139 INFO 2024-11-13 09:37:14,139 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-13 09:37:14,139 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:14,424 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:14,424 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:14,424 DEBUG --- stdout --- 2024-11-13 09:37:14,424 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:14,424 DEBUG --- stderr --- 2024-11-13 09:37:14,424 DEBUG 2024-11-13 09:37:14,424 INFO 2024-11-13 09:37:14,424 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-60/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/rc.txt 2024-11-13 09:37:14,424 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 09:37:15,222 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:15,222 DEBUG --- stdout --- 2024-11-13 09:37:15,222 DEBUG tar: Removing leading `/' from member names 2024-11-13 09:37:15,222 DEBUG --- stderr --- 2024-11-13 09:37:15,222 DEBUG 2024-11-13 09:37:15,223 INFO 2024-11-13 09:37:15,223 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-13 09:37:15,223 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:15,533 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:15,533 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:15,533 DEBUG --- stdout --- 2024-11-13 09:37:15,533 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:15,533 DEBUG --- stderr --- 2024-11-13 09:37:15,533 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 09:37:15,533 INFO 2024-11-13 09:37:15,533 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-13 09:37:15,533 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:15,847 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:15,847 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:15,847 DEBUG --- stdout --- 2024-11-13 09:37:15,847 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:15,847 DEBUG --- stderr --- 2024-11-13 09:37:15,847 DEBUG 2024-11-13 09:37:15,848 INFO 2024-11-13 09:37:15,848 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-60/stderr.txt" ]; then echo "/results/orders/benchmark-60/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-60/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-60/stderr.txt file found" 2024-11-13 09:37:15,848 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 09:37:16,713 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 09:37:16,713 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:16,713 DEBUG --- stdout --- 2024-11-13 09:37:16,713 DEBUG /results/orders/benchmark-60/stderr.txt file found 2024-11-13 09:37:16,713 DEBUG --- stderr --- 2024-11-13 09:37:16,713 DEBUG 2024-11-13 09:37:16,713 INFO 2024-11-13 09:37:16,714 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-13 09:37:16,714 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:16,999 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:17,000 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:17,000 DEBUG --- stdout --- 2024-11-13 09:37:17,000 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:17,000 DEBUG --- stderr --- 2024-11-13 09:37:17,000 DEBUG 2024-11-13 09:37:17,000 INFO 2024-11-13 09:37:17,000 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-60/stderr.txt 2024-11-13 09:37:17,829 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 09:37:17,829 DEBUG --- stdout --- 2024-11-13 09:37:17,829 DEBUG 0 /results/orders/benchmark-60/stderr.txt 2024-11-13 09:37:17,829 DEBUG --- stderr --- 2024-11-13 09:37:17,829 DEBUG 2024-11-13 09:37:17,829 INFO - Download file (size 0k) 2024-11-13 09:37:17,829 INFO 2024-11-13 09:37:17,829 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-13 09:37:17,829 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:18,114 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:18,114 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:18,114 DEBUG --- stdout --- 2024-11-13 09:37:18,114 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:18,114 DEBUG --- stderr --- 2024-11-13 09:37:18,114 DEBUG source : /results/orders/benchmark-60/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/stderr.txt 2024-11-13 09:37:18,114 INFO 2024-11-13 09:37:18,114 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-13 09:37:18,114 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:18,402 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:18,402 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:18,402 DEBUG --- stdout --- 2024-11-13 09:37:18,402 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:18,402 DEBUG --- stderr --- 2024-11-13 09:37:18,402 DEBUG 2024-11-13 09:37:18,403 INFO 2024-11-13 09:37:18,403 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-60/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/stderr.txt 2024-11-13 09:37:18,403 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 09:37:19,191 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:19,192 DEBUG --- stdout --- 2024-11-13 09:37:19,192 DEBUG tar: Removing leading `/' from member names 2024-11-13 09:37:19,192 DEBUG --- stderr --- 2024-11-13 09:37:19,192 DEBUG 2024-11-13 09:37:19,192 INFO 2024-11-13 09:37:19,192 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-13 09:37:19,192 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:19,478 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:19,478 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:19,478 DEBUG --- stdout --- 2024-11-13 09:37:19,478 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:19,478 DEBUG --- stderr --- 2024-11-13 09:37:19,478 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 09:37:19,479 INFO 2024-11-13 09:37:19,479 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-13 09:37:19,479 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:19,764 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:19,764 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:19,764 DEBUG --- stdout --- 2024-11-13 09:37:19,764 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:19,764 DEBUG --- stderr --- 2024-11-13 09:37:19,764 DEBUG 2024-11-13 09:37:19,765 INFO 2024-11-13 09:37:19,765 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-60/stdout.txt" ]; then echo "/results/orders/benchmark-60/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-60/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-60/stdout.txt file found" 2024-11-13 09:37:19,765 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 09:37:20,602 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 09:37:20,602 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:20,602 DEBUG --- stdout --- 2024-11-13 09:37:20,602 DEBUG /results/orders/benchmark-60/stdout.txt file found 2024-11-13 09:37:20,602 DEBUG --- stderr --- 2024-11-13 09:37:20,602 DEBUG 2024-11-13 09:37:20,602 INFO 2024-11-13 09:37:20,602 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-13 09:37:20,602 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:20,889 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:20,889 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:20,889 DEBUG --- stdout --- 2024-11-13 09:37:20,889 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:20,889 DEBUG --- stderr --- 2024-11-13 09:37:20,889 DEBUG 2024-11-13 09:37:20,889 INFO 2024-11-13 09:37:20,889 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-60/stdout.txt 2024-11-13 09:37:21,715 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 09:37:21,715 DEBUG --- stdout --- 2024-11-13 09:37:21,715 DEBUG 5560 /results/orders/benchmark-60/stdout.txt 2024-11-13 09:37:21,715 DEBUG --- stderr --- 2024-11-13 09:37:21,715 DEBUG 2024-11-13 09:37:21,715 INFO - Download file (size 5560k) 2024-11-13 09:37:21,716 INFO 2024-11-13 09:37:21,716 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-13 09:37:21,716 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:22,001 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:22,001 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:22,001 DEBUG --- stdout --- 2024-11-13 09:37:22,002 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:22,002 DEBUG --- stderr --- 2024-11-13 09:37:22,002 DEBUG source : /results/orders/benchmark-60/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/stdout.txt 2024-11-13 09:37:22,002 INFO 2024-11-13 09:37:22,002 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-13 09:37:22,002 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:22,284 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:22,284 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:22,284 DEBUG --- stdout --- 2024-11-13 09:37:22,284 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:22,284 DEBUG --- stderr --- 2024-11-13 09:37:22,284 DEBUG 2024-11-13 09:37:22,285 INFO 2024-11-13 09:37:22,285 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-60/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/stdout.txt 2024-11-13 09:37:22,285 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 09:37:23,604 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:23,604 DEBUG --- stdout --- 2024-11-13 09:37:23,604 DEBUG tar: Removing leading `/' from member names 2024-11-13 09:37:23,604 DEBUG --- stderr --- 2024-11-13 09:37:23,604 DEBUG ________________________________________________________________________________ [2024-11-13 09:37:23] benchmark-60 show_cmd : Display order command ________________________________________________________________________________ 2024-11-13 09:37:23,605 INFO 2024-11-13 09:37:23,605 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-13 09:37:23,605 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:23,902 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:23,902 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:23,902 DEBUG --- stdout --- 2024-11-13 09:37:23,902 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:23,902 DEBUG --- stderr --- 2024-11-13 09:37:23,902 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 09:37:23,902 INFO 2024-11-13 09:37:23,902 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-13 09:37:23,902 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:24,185 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:24,185 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:24,185 DEBUG --- stdout --- 2024-11-13 09:37:24,185 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:24,185 DEBUG --- stderr --- 2024-11-13 09:37:24,185 DEBUG 2024-11-13 09:37:24,186 INFO 2024-11-13 09:37:24,186 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-60/cmd.txt" ]; then echo "/results/orders/benchmark-60/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-60/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-60/cmd.txt file found" 2024-11-13 09:37:24,186 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 09:37:25,022 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 09:37:25,022 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:25,022 DEBUG --- stdout --- 2024-11-13 09:37:25,022 DEBUG /results/orders/benchmark-60/cmd.txt file found 2024-11-13 09:37:25,022 DEBUG --- stderr --- 2024-11-13 09:37:25,022 DEBUG 2024-11-13 09:37:25,023 INFO 2024-11-13 09:37:25,023 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-13 09:37:25,023 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:25,307 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:25,307 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:25,307 DEBUG --- stdout --- 2024-11-13 09:37:25,307 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:25,307 DEBUG --- stderr --- 2024-11-13 09:37:25,307 DEBUG 2024-11-13 09:37:25,307 INFO 2024-11-13 09:37:25,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-60/cmd.txt 2024-11-13 09:37:26,127 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 09:37:26,127 DEBUG --- stdout --- 2024-11-13 09:37:26,127 DEBUG 4 /results/orders/benchmark-60/cmd.txt 2024-11-13 09:37:26,127 DEBUG --- stderr --- 2024-11-13 09:37:26,127 DEBUG 2024-11-13 09:37:26,127 INFO - Download file (size 4k) 2024-11-13 09:37:26,127 INFO 2024-11-13 09:37:26,127 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-13 09:37:26,127 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:26,413 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:26,413 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:26,413 DEBUG --- stdout --- 2024-11-13 09:37:26,413 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:26,413 DEBUG --- stderr --- 2024-11-13 09:37:26,413 DEBUG source : /results/orders/benchmark-60/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/cmd.txt 2024-11-13 09:37:26,414 INFO 2024-11-13 09:37:26,414 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-13 09:37:26,414 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 09:37:26,697 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 09:37:26,697 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:26,697 DEBUG --- stdout --- 2024-11-13 09:37:26,697 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 09:37:26,697 DEBUG --- stderr --- 2024-11-13 09:37:26,697 DEBUG 2024-11-13 09:37:26,698 INFO 2024-11-13 09:37:26,698 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-60/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/cmd.txt 2024-11-13 09:37:26,698 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 09:37:27,485 INFO [loop_until]: OK (rc = 0) 2024-11-13 09:37:27,485 DEBUG --- stdout --- 2024-11-13 09:37:27,485 DEBUG tar: Removing leading `/' from member names 2024-11-13 09:37:27,485 DEBUG --- stderr --- 2024-11-13 09:37:27,485 DEBUG 2024-11-13 09:37:27,485 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/cmd.txt is small (1 lines), printing all lines: 2024-11-13 09:37:27,485 DEBUG ----- output ----- 2024-11-13 09:37:27,485 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-60" --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=self60- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=60 -Duser_password=Pa_ssw0rd" 2024-11-13 09:37:27,485 INFO ________________________________________________________________________________ [2024-11-13 09:37:27] benchmark-60 show_rc : Display rc order ________________________________________________________________________________ 2024-11-13 09:37:27,485 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/rc.txt is small (1 lines), printing all lines: 2024-11-13 09:37:27,485 DEBUG ----- output ----- 2024-11-13 09:37:27,485 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-13 09:37:27,485 INFO ________________________________________________________________________________ [2024-11-13 09:37:27] benchmark-60 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-13 09:37:27,492 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/stdout.txt is 64736 lines): 2024-11-13 09:37:27,492 DEBUG ----- output ----- 2024-11-13 09:37:27,492 INFO 2024-11-13 08:36:49,424 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpj97_umwa 2024-11-13 09:37:27,492 INFO 2024-11-13 08:36:49,426 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-60" -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=self60- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=60 -Duser_password=**** 2024-11-13 09:37:27,492 INFO [INFO] Scanning for projects... 2024-11-13 09:37:27,493 INFO [INFO] 2024-11-13 09:37:27,493 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-13 09:37:27,493 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-13 09:37:27,493 INFO [INFO] from pom.xml 2024-11-13 09:37:27,493 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-13 09:37:27,493 INFO [INFO] 2024-11-13 09:37:27,493 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-13 09:37:27,493 INFO [INFO] 2024-11-13 09:37:27,493 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-13 09:37:27,493 INFO [INFO] skip non existing resourceDirectory /tmp/tmpj97_umwa/src/main/resources 2024-11-13 09:37:27,493 INFO [INFO] 2024-11-13 09:37:27,493 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-13 09:37:27,493 INFO [INFO] No sources to compile 2024-11-13 09:37:27,493 INFO [INFO] 2024-11-13 09:37:27,493 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-13 09:37:27,493 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-13 09:37:27,493 INFO [INFO] 2024-11-13 09:37:27,493 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-13 09:37:27,493 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-13 09:37:27,493 INFO [INFO] 2024-11-13 09:37:27,493 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-13 09:37:27,493 INFO [INFO] compiling 38 Scala sources to /tmp/tmpj97_umwa/target/test-classes ... 2024-11-13 09:37:27,493 INFO [INFO] compile in 6.9 s 2024-11-13 09:37:27,493 INFO [INFO] 2024-11-13 09:37:27,493 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-13 09:37:27,493 INFO [INFO] 2024-11-13 09:37:27,493 INFO [INFO] 2024-11-13 09:37:27,493 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-13 09:37:27,493 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-13 09:37:27,493 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-13 09:37:27,493 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-13 09:37:27,493 INFO 2024-11-13 09:37:27,493 INFO ================================================================================ 2024-11-13 09:37:27,493 INFO 2024-11-13 08:37:05 GMT 5s elapsed 2024-11-13 09:37:27,493 INFO ---- Requests ------------------------------------------------------------------ 2024-11-13 09:37:27,493 INFO > Global (OK=256 KO=0 ) 2024-11-13 09:37:27,493 INFO > restLoginInitiate (OK=24 KO=0 ) 2024-11-13 09:37:27,493 INFO > restLoginUsernamePasswordCallback (OK=23 KO=0 ) 2024-11-13 09:37:27,493 INFO > Skip 2FA (OK=14 KO=0 ) 2024-11-13 09:37:27,493 INFO > User Authorize PKCE stage (OK=26 KO=0 ) 2024-11-13 09:37:27,493 INFO > User AccessToken PKCE stage (OK=25 KO=0 ) 2024-11-13 09:37:27,493 INFO > GetUUid (OK=7 KO=0 ) 2024-11-13 09:37:27,493 INFO > GetOpenidConfig (OK=7 KO=0 ) 2024-11-13 09:37:27,493 INFO > CreatePolicy (OK=7 KO=0 ) 2024-11-13 09:37:27,493 INFO > loginTreeWorkflow_restLoginInitiate (OK=1 KO=0 ) 2024-11-13 09:37:27,493 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI-Post-Login - stage 1 (OK=5 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI AM idFromSession (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI AM validateGoto (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI-Post-Login - stage 2 (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI Enduser appAuthHelper2 (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI Enduser appAuthHelper1 (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI-Post-Login - stage 3 (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI IDM uiconfig (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI Enduser sessioncheck (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI IDM version (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI IDM privilege (OK=4 KO=0 ) 2024-11-13 09:37:27,493 INFO > UI IDM login (OK=4 KO=0 ) 2024-11-13 09:37:27,494 INFO > UI IDM features (OK=4 KO=0 ) 2024-11-13 09:37:27,494 INFO > UI IDM dashboard (OK=4 KO=0 ) 2024-11-13 09:37:27,494 INFO > UI IDM managed user schema (OK=4 KO=0 ) 2024-11-13 09:37:27,494 INFO > UI IDM user (OK=4 KO=0 ) 2024-11-13 09:37:27,494 INFO > RootUserinfo (OK=8 KO=0 ) 2024-11-13 09:37:27,494 INFO > UI IDM oidcToken (OK=3 KO=0 ) 2024-11-13 09:37:27,494 INFO > userinfo (OK=3 KO=0 ) 2024-11-13 09:37:27,494 INFO > UI-Logout (OK=3 KO=0 ) 2024-11-13 09:37:27,494 INFO > UI-Logout Redirect 1 (OK=3 KO=0 ) 2024-11-13 09:37:27,494 INFO > UI AM revoke1 (OK=3 KO=0 ) 2024-11-13 09:37:27,494 INFO > UI AM endsession (OK=3 KO=0 ) 2024-11-13 09:37:27,494 INFO > UI AM revoke2 (OK=3 KO=0 ) 2024-11-13 09:37:27,494 INFO > getIdToken (OK=2 KO=0 ) 2024-11-13 09:37:27,494 INFO > getIdToken Redirect 1 (OK=2 KO=0 ) 2024-11-13 09:37:27,494 INFO > createOrganization (OK=2 KO=0 ) 2024-11-13 09:37:27,494 INFO > tokenUserinfo (OK=4 KO=0 ) 2024-11-13 09:37:27,494 INFO > queryOrganization (OK=2 KO=0 ) 2024-11-13 09:37:27,494 INFO > deleteOrganization (OK=2 KO=0 ) 2024-11-13 09:37:27,494 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-11-13 09:37:27,494 INFO 2024-11-13 09:37:27,494 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-13 09:37:27,494 INFO [- ] 0% 2024-11-13 09:37:27,494 INFO waiting: 216884 / active: 15 / done: 10 2024-11-13 09:37:27,494 INFO ================================================================================ 2024-11-13 09:37:27,494 INFO 2024-11-13 09:37:27,494 INFO 08:37:07.218 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-13 09:37:27,494 INFO 08:37:08.527 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-13 09:37:27,494 INFO 08:37:08.646 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-13 09:37:27,494 INFO 08:37:09.114 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-13 09:37:27,494 INFO 08:37:09.944 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-13 09:37:27,494 INFO 08:37:10.331 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-13 09:37:27,494 INFO 2024-11-13 09:37:27,494 INFO ================================================================================ 2024-11-13 09:37:27,494 INFO 2024-11-13 08:37:10 GMT 10s elapsed 2024-11-13 09:37:27,494 INFO ---- Requests ------------------------------------------------------------------ 2024-11-13 09:37:27,494 INFO > Global (OK=1015 KO=0 ) 2024-11-13 09:37:27,494 INFO [...] 2024-11-13 09:37:27,504 INFO headers: 2024-11-13 09:37:27,504 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-13 09:37:27,504 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-13 09:37:27,504 INFO Accept-Api-Version: 2024-11-13 09:37:27,504 INFO accept: */* 2024-11-13 09:37:27,504 INFO cookie: amlbcookie=01; 9b75a55744995d0=m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-13 09:37:27,504 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-13 09:37:27,504 INFO cookies: 2024-11-13 09:37:27,504 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 09:37:27,504 INFO 9b75a55744995d0=m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 09:37:27,504 INFO ========================= 2024-11-13 09:37:27,504 INFO HTTP response: 2024-11-13 09:37:27,504 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-13 09:37:27,504 INFO 09:37:00.371 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 215120: j.i.IOException: Premature close 2024-11-13 09:37:27,504 INFO 09:37:00.371 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-13 09:37:27,504 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-13 09:37:27,504 INFO Request: 2024-11-13 09:37:27,504 INFO UI IDM login: KO j.i.IOException: Premature close 2024-11-13 09:37:27,504 INFO ========================= 2024-11-13 09:37:27,504 INFO Session: 2024-11-13 09:37:27,504 INFO Session(idc.benchmark.Mix2024,215120,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NDdlZDk5Yy0xNTlkLTRkNjEtOTVjMi1kNDZlMDNmNTg2N2QiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMDdmNTQxOWYtOTZkNS00ZDM3LWJmOTgtYzU4ODYxMzA3NjhmLTI2NDI0MTI2Iiwic3VibmFtZSI6Ijc0N2VkOTljLTE1OWQtNGQ2MS05NWMyLWQ0NmUwM2Y1ODY3ZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6Ik9FcjB3dkZ6eFNuYngzMEpweGpGYkh6OUZMSSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ5MDYyMCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ5MDYxOSwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTQyMjAsImlhdCI6MTczMTQ5MDYyMCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiMG53dXlWM0p5OGtacnpnUUtlU185UHdGeWNnIn0.LBy5__Br36PRJ73SiFe3ym9LURb1uc55eTyASwoTIHs, username -> user.139867, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NDdlZDk5Yy0xNTlkLTRkNjEtOTVjMi1kNDZlMDNmNTg2N2QiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMDdmNTQxOWYtOTZkNS00ZDM3LWJmOTgtYzU4ODYxMzA3NjhmLTI2NDI0NTk0Iiwic3VibmFtZSI6Ijc0N2VkOTljLTE1OWQtNGQ2MS05NWMyLWQ0NmUwM2Y1ODY3ZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IjI0VnlhX2hFa202SUNNRmI4eEFGdVp4aVB4VSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ5MDYyMCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ5MDYxOSwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTQyMjAsImlhdCI6MTczMTQ5MDYyMCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiSnFQWnJNSVh3VmxxTnFVUW0tWjA2a3FBOVZNIn0.5GoVaMGAuhatOaenQjPbHR3n3INZaJ6B0p0YrU9gBrg, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@45fb6cc, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> cXbJlQv34G-LYSONNDIEOvFMZVo, callbacks -> {"tokenId":"m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731490619786), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731490619786))), user_uuid -> 747ed99c-159d-4d61-95c2-d46e03f5867d, password -> Pa_ssw0rd, challenge -> Vmf8RoPPmpApWNf_d_V8VIrsG4jfhsYjEegvRTWSeQU, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 247.27.121.245, verifier -> MzAwMjA2OTgzMzU1NTk5NTczMQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@425ca610, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@11bf542f, tokenId -> m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.157 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007866e869a0e0@165a2365,io.netty.channel.epoll.EpollEventLoop@636e8cc) 2024-11-13 09:37:27,504 INFO ========================= 2024-11-13 09:37:27,504 INFO HTTP request: 2024-11-13 09:37:27,504 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-13 09:37:27,504 INFO headers: 2024-11-13 09:37:27,504 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-13 09:37:27,504 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-13 09:37:27,504 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NDdlZDk5Yy0xNTlkLTRkNjEtOTVjMi1kNDZlMDNmNTg2N2QiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMDdmNTQxOWYtOTZkNS00ZDM3LWJmOTgtYzU4ODYxMzA3NjhmLTI2NDI0NTk0Iiwic3VibmFtZSI6Ijc0N2VkOTljLTE1OWQtNGQ2MS05NWMyLWQ0NmUwM2Y1ODY3ZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IjI0VnlhX2hFa202SUNNRmI4eEFGdVp4aVB4VSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ5MDYyMCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ5MDYxOSwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTQyMjAsImlhdCI6MTczMTQ5MDYyMCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiSnFQWnJNSVh3VmxxTnFVUW0tWjA2a3FBOVZNIn0.5GoVaMGAuhatOaenQjPbHR3n3INZaJ6B0p0YrU9gBrg 2024-11-13 09:37:27,504 INFO Accept-Api-Version: 2024-11-13 09:37:27,504 INFO accept: */* 2024-11-13 09:37:27,504 INFO cookie: amlbcookie=01; 9b75a55744995d0=m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-13 09:37:27,504 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-13 09:37:27,504 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-13 09:37:27,504 INFO content-length: 0 2024-11-13 09:37:27,504 INFO cookies: 2024-11-13 09:37:27,504 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 09:37:27,504 INFO 9b75a55744995d0=m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 09:37:27,504 INFO ========================= 2024-11-13 09:37:27,504 INFO HTTP response: 2024-11-13 09:37:27,504 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-13 09:37:27,504 INFO 09:37:00.371 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM features' failed for user 215120: j.i.IOException: Premature close 2024-11-13 09:37:27,504 INFO 09:37:00.371 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-13 09:37:27,504 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-13 09:37:27,504 INFO Request: 2024-11-13 09:37:27,504 INFO UI IDM features: KO j.i.IOException: Premature close 2024-11-13 09:37:27,505 INFO ========================= 2024-11-13 09:37:27,505 INFO Session: 2024-11-13 09:37:27,505 INFO Session(idc.benchmark.Mix2024,215120,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NDdlZDk5Yy0xNTlkLTRkNjEtOTVjMi1kNDZlMDNmNTg2N2QiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMDdmNTQxOWYtOTZkNS00ZDM3LWJmOTgtYzU4ODYxMzA3NjhmLTI2NDI0MTI2Iiwic3VibmFtZSI6Ijc0N2VkOTljLTE1OWQtNGQ2MS05NWMyLWQ0NmUwM2Y1ODY3ZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6Ik9FcjB3dkZ6eFNuYngzMEpweGpGYkh6OUZMSSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ5MDYyMCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ5MDYxOSwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTQyMjAsImlhdCI6MTczMTQ5MDYyMCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiMG53dXlWM0p5OGtacnpnUUtlU185UHdGeWNnIn0.LBy5__Br36PRJ73SiFe3ym9LURb1uc55eTyASwoTIHs, username -> user.139867, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NDdlZDk5Yy0xNTlkLTRkNjEtOTVjMi1kNDZlMDNmNTg2N2QiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMDdmNTQxOWYtOTZkNS00ZDM3LWJmOTgtYzU4ODYxMzA3NjhmLTI2NDI0NTk0Iiwic3VibmFtZSI6Ijc0N2VkOTljLTE1OWQtNGQ2MS05NWMyLWQ0NmUwM2Y1ODY3ZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IjI0VnlhX2hFa202SUNNRmI4eEFGdVp4aVB4VSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ5MDYyMCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ5MDYxOSwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTQyMjAsImlhdCI6MTczMTQ5MDYyMCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiSnFQWnJNSVh3VmxxTnFVUW0tWjA2a3FBOVZNIn0.5GoVaMGAuhatOaenQjPbHR3n3INZaJ6B0p0YrU9gBrg, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@45fb6cc, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> cXbJlQv34G-LYSONNDIEOvFMZVo, callbacks -> {"tokenId":"m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731490619786), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731490619786))), user_uuid -> 747ed99c-159d-4d61-95c2-d46e03f5867d, password -> Pa_ssw0rd, challenge -> Vmf8RoPPmpApWNf_d_V8VIrsG4jfhsYjEegvRTWSeQU, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 247.27.121.245, verifier -> MzAwMjA2OTgzMzU1NTk5NTczMQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@425ca610, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@11bf542f, tokenId -> m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.157 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007866e869a0e0@165a2365,io.netty.channel.epoll.EpollEventLoop@636e8cc) 2024-11-13 09:37:27,505 INFO ========================= 2024-11-13 09:37:27,505 INFO HTTP request: 2024-11-13 09:37:27,505 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/features?_queryFilter=true 2024-11-13 09:37:27,505 INFO headers: 2024-11-13 09:37:27,505 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-13 09:37:27,505 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-13 09:37:27,505 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NDdlZDk5Yy0xNTlkLTRkNjEtOTVjMi1kNDZlMDNmNTg2N2QiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMDdmNTQxOWYtOTZkNS00ZDM3LWJmOTgtYzU4ODYxMzA3NjhmLTI2NDI0NTk0Iiwic3VibmFtZSI6Ijc0N2VkOTljLTE1OWQtNGQ2MS05NWMyLWQ0NmUwM2Y1ODY3ZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IjI0VnlhX2hFa202SUNNRmI4eEFGdVp4aVB4VSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ5MDYyMCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ5MDYxOSwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTQyMjAsImlhdCI6MTczMTQ5MDYyMCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiSnFQWnJNSVh3VmxxTnFVUW0tWjA2a3FBOVZNIn0.5GoVaMGAuhatOaenQjPbHR3n3INZaJ6B0p0YrU9gBrg 2024-11-13 09:37:27,505 INFO Accept-Api-Version: 2024-11-13 09:37:27,505 INFO accept: */* 2024-11-13 09:37:27,505 INFO cookie: amlbcookie=01; 9b75a55744995d0=m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-13 09:37:27,505 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-13 09:37:27,505 INFO cookies: 2024-11-13 09:37:27,505 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 09:37:27,505 INFO 9b75a55744995d0=m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 09:37:27,505 INFO ========================= 2024-11-13 09:37:27,505 INFO HTTP response: 2024-11-13 09:37:27,505 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-13 09:37:27,505 INFO 09:37:00.371 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM privilege' failed for user 215120: j.i.IOException: Premature close 2024-11-13 09:37:27,505 INFO 09:37:00.371 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-13 09:37:27,505 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-13 09:37:27,505 INFO Request: 2024-11-13 09:37:27,505 INFO UI IDM privilege: KO j.i.IOException: Premature close 2024-11-13 09:37:27,505 INFO ========================= 2024-11-13 09:37:27,505 INFO Session: 2024-11-13 09:37:27,505 INFO Session(idc.benchmark.Mix2024,215120,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NDdlZDk5Yy0xNTlkLTRkNjEtOTVjMi1kNDZlMDNmNTg2N2QiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMDdmNTQxOWYtOTZkNS00ZDM3LWJmOTgtYzU4ODYxMzA3NjhmLTI2NDI0MTI2Iiwic3VibmFtZSI6Ijc0N2VkOTljLTE1OWQtNGQ2MS05NWMyLWQ0NmUwM2Y1ODY3ZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6Ik9FcjB3dkZ6eFNuYngzMEpweGpGYkh6OUZMSSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ5MDYyMCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ5MDYxOSwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTQyMjAsImlhdCI6MTczMTQ5MDYyMCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiMG53dXlWM0p5OGtacnpnUUtlU185UHdGeWNnIn0.LBy5__Br36PRJ73SiFe3ym9LURb1uc55eTyASwoTIHs, username -> user.139867, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NDdlZDk5Yy0xNTlkLTRkNjEtOTVjMi1kNDZlMDNmNTg2N2QiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMDdmNTQxOWYtOTZkNS00ZDM3LWJmOTgtYzU4ODYxMzA3NjhmLTI2NDI0NTk0Iiwic3VibmFtZSI6Ijc0N2VkOTljLTE1OWQtNGQ2MS05NWMyLWQ0NmUwM2Y1ODY3ZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IjI0VnlhX2hFa202SUNNRmI4eEFGdVp4aVB4VSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ5MDYyMCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ5MDYxOSwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTQyMjAsImlhdCI6MTczMTQ5MDYyMCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiSnFQWnJNSVh3VmxxTnFVUW0tWjA2a3FBOVZNIn0.5GoVaMGAuhatOaenQjPbHR3n3INZaJ6B0p0YrU9gBrg, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@45fb6cc, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> cXbJlQv34G-LYSONNDIEOvFMZVo, callbacks -> {"tokenId":"m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731490619786), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731490619786))), user_uuid -> 747ed99c-159d-4d61-95c2-d46e03f5867d, password -> Pa_ssw0rd, challenge -> Vmf8RoPPmpApWNf_d_V8VIrsG4jfhsYjEegvRTWSeQU, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 247.27.121.245, verifier -> MzAwMjA2OTgzMzU1NTk5NTczMQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@425ca610, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@11bf542f, tokenId -> m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.157 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007866e869a0e0@165a2365,io.netty.channel.epoll.EpollEventLoop@636e8cc) 2024-11-13 09:37:27,505 INFO ========================= 2024-11-13 09:37:27,505 INFO HTTP request: 2024-11-13 09:37:27,505 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/privilege?_action=listPrivileges 2024-11-13 09:37:27,505 INFO headers: 2024-11-13 09:37:27,505 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-13 09:37:27,505 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-13 09:37:27,505 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NDdlZDk5Yy0xNTlkLTRkNjEtOTVjMi1kNDZlMDNmNTg2N2QiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMDdmNTQxOWYtOTZkNS00ZDM3LWJmOTgtYzU4ODYxMzA3NjhmLTI2NDI0NTk0Iiwic3VibmFtZSI6Ijc0N2VkOTljLTE1OWQtNGQ2MS05NWMyLWQ0NmUwM2Y1ODY3ZCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IjI0VnlhX2hFa202SUNNRmI4eEFGdVp4aVB4VSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ5MDYyMCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ5MDYxOSwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTQyMjAsImlhdCI6MTczMTQ5MDYyMCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiSnFQWnJNSVh3VmxxTnFVUW0tWjA2a3FBOVZNIn0.5GoVaMGAuhatOaenQjPbHR3n3INZaJ6B0p0YrU9gBrg 2024-11-13 09:37:27,505 INFO Accept-Api-Version: 2024-11-13 09:37:27,505 INFO accept: */* 2024-11-13 09:37:27,505 INFO cookie: amlbcookie=01; 9b75a55744995d0=m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-13 09:37:27,505 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-13 09:37:27,505 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-13 09:37:27,505 INFO content-length: 0 2024-11-13 09:37:27,505 INFO cookies: 2024-11-13 09:37:27,505 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 09:37:27,505 INFO 9b75a55744995d0=m7lJirmob6e5JOm2gOGmYiAppHU.*AAJTSQACMDIAAlNLABxqYzZKenFEK0crL0pHeFhmZStHUHkxaitDakk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 09:37:27,505 INFO ========================= 2024-11-13 09:37:27,505 INFO HTTP response: 2024-11-13 09:37:27,505 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-13 09:37:27,505 INFO [INFO] ------------------------------------------------------------------------ 2024-11-13 09:37:27,505 INFO [INFO] BUILD SUCCESS 2024-11-13 09:37:27,505 INFO [INFO] ------------------------------------------------------------------------ 2024-11-13 09:37:27,505 INFO [INFO] Total time: 01:00 h 2024-11-13 09:37:27,505 INFO [INFO] Finished at: 2024-11-13T09:37:00Z 2024-11-13 09:37:27,505 INFO [INFO] ------------------------------------------------------------------------ 2024-11-13 09:37:27,505 INFO 2024-11-13 09:37:00,465 INFO Gatling simulation run ended successfully 2024-11-13 09:37:27,505 INFO 2024-11-13 09:37:00,465 DEBUG No archive name provided. No archive will be created 2024-11-13 09:37:27,505 INFO ________________________________________________________________________________ [2024-11-13 09:37:27] benchmark-60 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-13 09:37:27,506 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-60/stderr.txt is small (0 lines), printing all lines: 2024-11-13 09:37:27,506 DEBUG ----- output ----- 2024-11-13 09:37:27,506 DEBUG 2024-11-13 09:37:27,506 INFO ________________________________________________________________________________ [2024-11-13 09:37:27] benchmark-60 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-13 09:37:27] benchmark-60 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-13 09:37:27] benchmark-60 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped