--Task-- name: benchmark-50 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-30'] preceding_task: benchmark-30 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self50-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 50} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-13 07:36:03] benchmark-50 pre : N/A ________________________________________________________________________________ 2024-11-13 07:36:03 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-13 07:36:03 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-50/stdout.txt Pod log : /results/orders/benchmark-50 2024-11-13 07:36:03,172 INFO 2024-11-13 07:36:03,172 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 07:36:03,172 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 07:36:03,457 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 07:36:03,457 INFO [loop_until]: OK (rc = 0) 2024-11-13 07:36:03,457 DEBUG --- stdout --- 2024-11-13 07:36:03,457 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 07:36:03,457 DEBUG --- stderr --- 2024-11-13 07:36:03,457 DEBUG ________________________________________________________________________________ [2024-11-13 07:36:03] benchmark-50 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-13 07:36:03,457 INFO 2024-11-13 07:36:03,457 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 07:36:03,457 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 07:36:03,742 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 07:36:03,742 INFO [loop_until]: OK (rc = 0) 2024-11-13 07:36:03,742 DEBUG --- stdout --- 2024-11-13 07:36:03,742 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 07:36:03,742 DEBUG --- stderr --- 2024-11-13 07:36:03,742 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-50" --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=self50- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=50 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-13 07:36:03] benchmark-50 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-13 07:36:03,743 INFO 2024-11-13 07:36:03,743 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 07:36:03,743 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 07:36:04,027 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 07:36:04,027 INFO [loop_until]: OK (rc = 0) 2024-11-13 07:36:04,027 DEBUG --- stdout --- 2024-11-13 07:36:04,027 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 07:36:04,027 DEBUG --- stderr --- 2024-11-13 07:36:04,027 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/order.json { "name": "benchmark-50", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-50\" --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=self50- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=50 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-50" } ________________________________________________________________________________ [2024-11-13 07:36:04] benchmark-50 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 07:36:04,030 INFO 2024-11-13 07:36:04,030 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-13 07:36:04,030 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-13 07:36:04,255 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 07:36:04,255 INFO [loop_until]: OK (rc = 0) 2024-11-13 07:36:04,255 DEBUG --- stdout --- 2024-11-13 07:36:04,255 DEBUG WAITING 2024-11-13 07:36:04,255 DEBUG --- stderr --- 2024-11-13 07:36:04,255 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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-11-13 07:36:04] benchmark-50 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-13 07:36:04,256 INFO 2024-11-13 07:36:04,256 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-13 07:36:04,256 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 07:36:04,540 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 07:36:04,540 INFO [loop_until]: OK (rc = 0) 2024-11-13 07:36:04,540 DEBUG --- stdout --- 2024-11-13 07:36:04,540 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 07:36:04,540 DEBUG --- stderr --- 2024-11-13 07:36:04,540 DEBUG 2024-11-13 07:36:04,540 INFO 2024-11-13 07:36:04,540 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-50/order.json overseer-0-6644bb6697-rchxr:/results/orders/order.json 2024-11-13 07:36:04,540 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 07:36:05,945 INFO [loop_until]: OK (rc = 0) 2024-11-13 07:36:05,946 DEBUG --- stdout --- 2024-11-13 07:36:05,946 DEBUG 2024-11-13 07:36:05,946 DEBUG --- stderr --- 2024-11-13 07:36:05,946 DEBUG Order has been successfully forwarded to overseer 2024-11-13 07:36:05 - INFO: Check if Gatling simulation started successfully 2024-11-13 07:36:05,946 INFO 2024-11-13 07:36:05,946 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-13 07:36:05,946 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 07:36:06,233 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 07:36:06,233 INFO [loop_until]: OK (rc = 0) 2024-11-13 07:36:06,233 DEBUG --- stdout --- 2024-11-13 07:36:06,233 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 07:36:06,233 DEBUG --- stderr --- 2024-11-13 07:36:06,233 DEBUG 2024-11-13 07:36:06,234 INFO 2024-11-13 07:36:06,234 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-50/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-13 07:36:06,234 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-13 07:36:07,022 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-13 07:36:12,774 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-13 07:36:18,527 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-13 07:36:18,527 INFO [loop_until]: OK (rc = 0) 2024-11-13 07:36:18,527 DEBUG --- stdout --- 2024-11-13 07:36:18,527 DEBUG 2024-11-13 07:36:06,594 DEBUG Creating results folder /results/gatling/benchmark-50 2024-11-13 07:36:06,601 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpv07w6gxb 2024-11-13 07:36:06,603 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-50" -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=self50- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=50 -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/tmpv07w6gxb/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/tmpv07w6gxb/target/test-classes ... [INFO] compile in 6.8 s [INFO] [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< [INFO] [INFO] [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- [INFO] Running simulation com.forgerock.pyrock.Loadtest. 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 07:36:18,527 DEBUG --- stderr --- 2024-11-13 07:36:18,527 DEBUG ________________________________________________________________________________ [2024-11-13 07:36:18] benchmark-50 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 08:36:27] benchmark-50 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-13 08:36:27,517 INFO 2024-11-13 08:36:27,517 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:27,517 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:27,826 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:27,826 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:27,826 DEBUG --- stdout --- 2024-11-13 08:36:27,826 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:27,826 DEBUG --- stderr --- 2024-11-13 08:36:27,826 DEBUG 2024-11-13 08:36:27,826 INFO 2024-11-13 08:36:27,826 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 08:36:27,826 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 08:36:28,625 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:28,625 DEBUG --- stdout --- 2024-11-13 08:36:28,626 DEBUG 2024-11-13 08:36:28,626 DEBUG --- stderr --- 2024-11-13 08:36:28,626 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-13 08:36:28] benchmark-50 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-13 08:36:28 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/order.json 2024-11-13 08:36:28,626 INFO 2024-11-13 08:36:28,626 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:28,626 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:28,913 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:28,913 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:28,913 DEBUG --- stdout --- 2024-11-13 08:36:28,913 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:28,913 DEBUG --- stderr --- 2024-11-13 08:36:28,913 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 08:36:28,913 INFO 2024-11-13 08:36:28,913 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:28,913 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:29,199 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:29,199 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:29,199 DEBUG --- stdout --- 2024-11-13 08:36:29,199 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:29,199 DEBUG --- stderr --- 2024-11-13 08:36:29,199 DEBUG 2024-11-13 08:36:29,200 INFO 2024-11-13 08:36:29,200 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-50/rc.txt" ]; then echo "/results/orders/benchmark-50/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-50/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-50/rc.txt file found" 2024-11-13 08:36:29,200 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 08:36:30,037 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 08:36:30,037 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:30,037 DEBUG --- stdout --- 2024-11-13 08:36:30,037 DEBUG /results/orders/benchmark-50/rc.txt file found 2024-11-13 08:36:30,037 DEBUG --- stderr --- 2024-11-13 08:36:30,037 DEBUG 2024-11-13 08:36:30,038 INFO 2024-11-13 08:36:30,038 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:30,038 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:30,325 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:30,325 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:30,325 DEBUG --- stdout --- 2024-11-13 08:36:30,325 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:30,325 DEBUG --- stderr --- 2024-11-13 08:36:30,325 DEBUG 2024-11-13 08:36:30,325 INFO 2024-11-13 08:36:30,325 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-50/rc.txt 2024-11-13 08:36:31,146 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 08:36:31,146 DEBUG --- stdout --- 2024-11-13 08:36:31,146 DEBUG 4 /results/orders/benchmark-50/rc.txt 2024-11-13 08:36:31,146 DEBUG --- stderr --- 2024-11-13 08:36:31,146 DEBUG 2024-11-13 08:36:31,146 INFO - Download file (size 4k) 2024-11-13 08:36:31,147 INFO 2024-11-13 08:36:31,147 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:31,147 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:31,434 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:31,434 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:31,434 DEBUG --- stdout --- 2024-11-13 08:36:31,434 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:31,434 DEBUG --- stderr --- 2024-11-13 08:36:31,434 DEBUG source : /results/orders/benchmark-50/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/rc.txt 2024-11-13 08:36:31,434 INFO 2024-11-13 08:36:31,434 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-13 08:36:31,434 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:31,720 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:31,720 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:31,720 DEBUG --- stdout --- 2024-11-13 08:36:31,720 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:31,720 DEBUG --- stderr --- 2024-11-13 08:36:31,720 DEBUG 2024-11-13 08:36:31,720 INFO 2024-11-13 08:36:31,721 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-50/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/rc.txt 2024-11-13 08:36:31,721 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 08:36:32,508 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:32,508 DEBUG --- stdout --- 2024-11-13 08:36:32,508 DEBUG tar: Removing leading `/' from member names 2024-11-13 08:36:32,508 DEBUG --- stderr --- 2024-11-13 08:36:32,508 DEBUG 2024-11-13 08:36:32,508 INFO 2024-11-13 08:36:32,508 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:32,508 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:32,795 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:32,795 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:32,795 DEBUG --- stdout --- 2024-11-13 08:36:32,795 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:32,795 DEBUG --- stderr --- 2024-11-13 08:36:32,795 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 08:36:32,795 INFO 2024-11-13 08:36:32,795 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:32,795 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:33,080 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:33,080 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:33,080 DEBUG --- stdout --- 2024-11-13 08:36:33,080 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:33,080 DEBUG --- stderr --- 2024-11-13 08:36:33,080 DEBUG 2024-11-13 08:36:33,081 INFO 2024-11-13 08:36:33,081 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-50/stderr.txt" ]; then echo "/results/orders/benchmark-50/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-50/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-50/stderr.txt file found" 2024-11-13 08:36:33,081 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 08:36:33,906 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 08:36:33,906 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:33,906 DEBUG --- stdout --- 2024-11-13 08:36:33,906 DEBUG /results/orders/benchmark-50/stderr.txt file found 2024-11-13 08:36:33,906 DEBUG --- stderr --- 2024-11-13 08:36:33,906 DEBUG 2024-11-13 08:36:33,907 INFO 2024-11-13 08:36:33,907 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:33,907 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:34,193 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:34,193 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:34,193 DEBUG --- stdout --- 2024-11-13 08:36:34,194 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:34,194 DEBUG --- stderr --- 2024-11-13 08:36:34,194 DEBUG 2024-11-13 08:36:34,194 INFO 2024-11-13 08:36:34,194 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-50/stderr.txt 2024-11-13 08:36:35,016 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 08:36:35,016 DEBUG --- stdout --- 2024-11-13 08:36:35,016 DEBUG 0 /results/orders/benchmark-50/stderr.txt 2024-11-13 08:36:35,016 DEBUG --- stderr --- 2024-11-13 08:36:35,016 DEBUG 2024-11-13 08:36:35,017 INFO - Download file (size 0k) 2024-11-13 08:36:35,017 INFO 2024-11-13 08:36:35,017 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:35,017 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:35,305 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:35,305 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:35,305 DEBUG --- stdout --- 2024-11-13 08:36:35,305 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:35,305 DEBUG --- stderr --- 2024-11-13 08:36:35,305 DEBUG source : /results/orders/benchmark-50/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/stderr.txt 2024-11-13 08:36:35,306 INFO 2024-11-13 08:36:35,306 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:35,306 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:35,591 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:35,591 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:35,591 DEBUG --- stdout --- 2024-11-13 08:36:35,591 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:35,591 DEBUG --- stderr --- 2024-11-13 08:36:35,591 DEBUG 2024-11-13 08:36:35,591 INFO 2024-11-13 08:36:35,591 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-50/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/stderr.txt 2024-11-13 08:36:35,591 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 08:36:36,374 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:36,374 DEBUG --- stdout --- 2024-11-13 08:36:36,374 DEBUG tar: Removing leading `/' from member names 2024-11-13 08:36:36,374 DEBUG --- stderr --- 2024-11-13 08:36:36,374 DEBUG 2024-11-13 08:36:36,375 INFO 2024-11-13 08:36:36,375 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:36,375 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:36,662 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:36,662 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:36,662 DEBUG --- stdout --- 2024-11-13 08:36:36,662 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:36,662 DEBUG --- stderr --- 2024-11-13 08:36:36,662 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 08:36:36,662 INFO 2024-11-13 08:36:36,662 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:36,662 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:36,947 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:36,947 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:36,947 DEBUG --- stdout --- 2024-11-13 08:36:36,947 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:36,947 DEBUG --- stderr --- 2024-11-13 08:36:36,947 DEBUG 2024-11-13 08:36:36,948 INFO 2024-11-13 08:36:36,948 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-50/stdout.txt" ]; then echo "/results/orders/benchmark-50/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-50/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-50/stdout.txt file found" 2024-11-13 08:36:36,948 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 08:36:37,766 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 08:36:37,766 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:37,766 DEBUG --- stdout --- 2024-11-13 08:36:37,766 DEBUG /results/orders/benchmark-50/stdout.txt file found 2024-11-13 08:36:37,766 DEBUG --- stderr --- 2024-11-13 08:36:37,766 DEBUG 2024-11-13 08:36:37,767 INFO 2024-11-13 08:36:37,767 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:37,767 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:38,054 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:38,054 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:38,054 DEBUG --- stdout --- 2024-11-13 08:36:38,054 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:38,054 DEBUG --- stderr --- 2024-11-13 08:36:38,054 DEBUG 2024-11-13 08:36:38,054 INFO 2024-11-13 08:36:38,054 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-50/stdout.txt 2024-11-13 08:36:38,923 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 08:36:38,923 DEBUG --- stdout --- 2024-11-13 08:36:38,923 DEBUG 4520 /results/orders/benchmark-50/stdout.txt 2024-11-13 08:36:38,923 DEBUG --- stderr --- 2024-11-13 08:36:38,923 DEBUG 2024-11-13 08:36:38,923 INFO - Download file (size 4520k) 2024-11-13 08:36:38,923 INFO 2024-11-13 08:36:38,923 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:38,923 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:39,208 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:39,208 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:39,208 DEBUG --- stdout --- 2024-11-13 08:36:39,208 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:39,208 DEBUG --- stderr --- 2024-11-13 08:36:39,208 DEBUG source : /results/orders/benchmark-50/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/stdout.txt 2024-11-13 08:36:39,209 INFO 2024-11-13 08:36:39,209 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:39,209 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:39,495 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:39,495 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:39,495 DEBUG --- stdout --- 2024-11-13 08:36:39,495 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:39,495 DEBUG --- stderr --- 2024-11-13 08:36:39,495 DEBUG 2024-11-13 08:36:39,496 INFO 2024-11-13 08:36:39,496 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-50/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/stdout.txt 2024-11-13 08:36:39,496 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 08:36:40,840 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:40,840 DEBUG --- stdout --- 2024-11-13 08:36:40,840 DEBUG tar: Removing leading `/' from member names 2024-11-13 08:36:40,840 DEBUG --- stderr --- 2024-11-13 08:36:40,840 DEBUG ________________________________________________________________________________ [2024-11-13 08:36:40] benchmark-50 show_cmd : Display order command ________________________________________________________________________________ 2024-11-13 08:36:40,840 INFO 2024-11-13 08:36:40,840 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-13 08:36:40,840 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:41,125 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:41,125 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:41,125 DEBUG --- stdout --- 2024-11-13 08:36:41,125 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:41,125 DEBUG --- stderr --- 2024-11-13 08:36:41,125 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 08:36:41,125 INFO 2024-11-13 08:36:41,125 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:41,125 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:41,413 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:41,413 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:41,413 DEBUG --- stdout --- 2024-11-13 08:36:41,413 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:41,413 DEBUG --- stderr --- 2024-11-13 08:36:41,413 DEBUG 2024-11-13 08:36:41,414 INFO 2024-11-13 08:36:41,414 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-50/cmd.txt" ]; then echo "/results/orders/benchmark-50/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-50/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-50/cmd.txt file found" 2024-11-13 08:36:41,414 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 08:36:42,241 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 08:36:42,241 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:42,241 DEBUG --- stdout --- 2024-11-13 08:36:42,241 DEBUG /results/orders/benchmark-50/cmd.txt file found 2024-11-13 08:36:42,241 DEBUG --- stderr --- 2024-11-13 08:36:42,241 DEBUG 2024-11-13 08:36:42,241 INFO 2024-11-13 08:36:42,241 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:42,241 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:42,525 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:42,526 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:42,526 DEBUG --- stdout --- 2024-11-13 08:36:42,526 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:42,526 DEBUG --- stderr --- 2024-11-13 08:36:42,526 DEBUG 2024-11-13 08:36:42,526 INFO 2024-11-13 08:36:42,526 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-50/cmd.txt 2024-11-13 08:36:43,353 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 08:36:43,353 DEBUG --- stdout --- 2024-11-13 08:36:43,353 DEBUG 4 /results/orders/benchmark-50/cmd.txt 2024-11-13 08:36:43,353 DEBUG --- stderr --- 2024-11-13 08:36:43,353 DEBUG 2024-11-13 08:36:43,353 INFO - Download file (size 4k) 2024-11-13 08:36:43,354 INFO 2024-11-13 08:36:43,354 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:43,354 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:43,668 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:43,668 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:43,668 DEBUG --- stdout --- 2024-11-13 08:36:43,668 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:43,668 DEBUG --- stderr --- 2024-11-13 08:36:43,668 DEBUG source : /results/orders/benchmark-50/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/cmd.txt 2024-11-13 08:36:43,669 INFO 2024-11-13 08:36:43,669 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:43,669 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 08:36:43,959 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 08:36:43,959 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:43,959 DEBUG --- stdout --- 2024-11-13 08:36:43,959 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 08:36:43,959 DEBUG --- stderr --- 2024-11-13 08:36:43,959 DEBUG 2024-11-13 08:36:43,960 INFO 2024-11-13 08:36:43,960 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-50/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/cmd.txt 2024-11-13 08:36:43,960 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 08:36:44,751 INFO [loop_until]: OK (rc = 0) 2024-11-13 08:36:44,751 DEBUG --- stdout --- 2024-11-13 08:36:44,751 DEBUG tar: Removing leading `/' from member names 2024-11-13 08:36:44,751 DEBUG --- stderr --- 2024-11-13 08:36:44,751 DEBUG 2024-11-13 08:36:44,751 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/cmd.txt is small (1 lines), printing all lines: 2024-11-13 08:36:44,751 DEBUG ----- output ----- 2024-11-13 08:36:44,751 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-50" --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=self50- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=50 -Duser_password=Pa_ssw0rd" 2024-11-13 08:36:44,751 INFO ________________________________________________________________________________ [2024-11-13 08:36:44] benchmark-50 show_rc : Display rc order ________________________________________________________________________________ 2024-11-13 08:36:44,751 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/rc.txt is small (1 lines), printing all lines: 2024-11-13 08:36:44,751 DEBUG ----- output ----- 2024-11-13 08:36:44,751 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-13 08:36:44,751 INFO ________________________________________________________________________________ [2024-11-13 08:36:44] benchmark-50 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-13 08:36:44,757 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-50/stdout.txt is 54891 lines): 2024-11-13 08:36:44,758 DEBUG ----- output ----- 2024-11-13 08:36:44,758 INFO 2024-11-13 07:36:06,594 DEBUG Creating results folder /results/gatling/benchmark-50 2024-11-13 08:36:44,758 INFO 2024-11-13 07:36:06,601 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpv07w6gxb 2024-11-13 08:36:44,758 INFO 2024-11-13 07:36:06,603 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-50" -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=self50- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=50 -Duser_password=**** 2024-11-13 08:36:44,758 INFO [INFO] Scanning for projects... 2024-11-13 08:36:44,758 INFO [INFO] 2024-11-13 08:36:44,758 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-13 08:36:44,758 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-13 08:36:44,758 INFO [INFO] from pom.xml 2024-11-13 08:36:44,758 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-13 08:36:44,758 INFO [INFO] 2024-11-13 08:36:44,758 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-13 08:36:44,758 INFO [INFO] 2024-11-13 08:36:44,758 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-13 08:36:44,758 INFO [INFO] skip non existing resourceDirectory /tmp/tmpv07w6gxb/src/main/resources 2024-11-13 08:36:44,758 INFO [INFO] 2024-11-13 08:36:44,758 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-13 08:36:44,758 INFO [INFO] No sources to compile 2024-11-13 08:36:44,758 INFO [INFO] 2024-11-13 08:36:44,758 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-13 08:36:44,758 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-13 08:36:44,758 INFO [INFO] 2024-11-13 08:36:44,758 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-13 08:36:44,758 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-13 08:36:44,758 INFO [INFO] 2024-11-13 08:36:44,758 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-13 08:36:44,758 INFO [INFO] compiling 38 Scala sources to /tmp/tmpv07w6gxb/target/test-classes ... 2024-11-13 08:36:44,758 INFO [INFO] compile in 6.8 s 2024-11-13 08:36:44,758 INFO [INFO] 2024-11-13 08:36:44,758 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-13 08:36:44,758 INFO [INFO] 2024-11-13 08:36:44,758 INFO [INFO] 2024-11-13 08:36:44,758 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-13 08:36:44,758 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-13 08:36:44,758 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-13 08:36:44,758 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-13 08:36:44,758 INFO 2024-11-13 08:36:44,758 INFO ================================================================================ 2024-11-13 08:36:44,758 INFO 2024-11-13 07:36:22 GMT 5s elapsed 2024-11-13 08:36:44,758 INFO ---- Requests ------------------------------------------------------------------ 2024-11-13 08:36:44,758 INFO > Global (OK=189 KO=0 ) 2024-11-13 08:36:44,758 INFO > restLoginInitiate (OK=18 KO=0 ) 2024-11-13 08:36:44,758 INFO > restLoginUsernamePasswordCallback (OK=17 KO=0 ) 2024-11-13 08:36:44,758 INFO > UI-Post-Login - stage 1 (OK=4 KO=0 ) 2024-11-13 08:36:44,758 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=4 KO=0 ) 2024-11-13 08:36:44,758 INFO > UI AM idFromSession (OK=3 KO=0 ) 2024-11-13 08:36:44,758 INFO > UI AM validateGoto (OK=3 KO=0 ) 2024-11-13 08:36:44,758 INFO > User Authorize PKCE stage (OK=19 KO=0 ) 2024-11-13 08:36:44,758 INFO > User AccessToken PKCE stage (OK=19 KO=0 ) 2024-11-13 08:36:44,758 INFO > UI-Post-Login - stage 2 (OK=3 KO=0 ) 2024-11-13 08:36:44,758 INFO > Skip 2FA (OK=7 KO=0 ) 2024-11-13 08:36:44,758 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=3 KO=0 ) 2024-11-13 08:36:44,758 INFO > UI Enduser appAuthHelper1 (OK=3 KO=0 ) 2024-11-13 08:36:44,758 INFO > UI Enduser appAuthHelper2 (OK=3 KO=0 ) 2024-11-13 08:36:44,758 INFO > GetUUid (OK=7 KO=0 ) 2024-11-13 08:36:44,758 INFO > UI-Post-Login - stage 3 (OK=3 KO=0 ) 2024-11-13 08:36:44,758 INFO > GetOpenidConfig (OK=6 KO=0 ) 2024-11-13 08:36:44,758 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI IDM uiconfig (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI Enduser sessioncheck (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > CreatePolicy (OK=6 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI IDM version (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI IDM features (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI IDM privilege (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI IDM login (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI IDM managed user schema (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI IDM dashboard (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI IDM user (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI IDM oidcToken (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > tokenUserinfo (OK=6 KO=0 ) 2024-11-13 08:36:44,759 INFO > userinfo (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI-Logout (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI-Logout Redirect 1 (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI AM endsession (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI AM revoke2 (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI AM revoke1 (OK=3 KO=0 ) 2024-11-13 08:36:44,759 INFO > loginTreeWorkflow_restLoginInitiate (OK=2 KO=0 ) 2024-11-13 08:36:44,759 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=2 KO=0 ) 2024-11-13 08:36:44,759 INFO 2024-11-13 08:36:44,759 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-13 08:36:44,759 INFO [- ] 0% 2024-11-13 08:36:44,759 INFO waiting: 180824 / active: 9 / done: 11 2024-11-13 08:36:44,759 INFO ================================================================================ 2024-11-13 08:36:44,759 INFO 2024-11-13 08:36:44,759 INFO 2024-11-13 08:36:44,759 INFO ================================================================================ 2024-11-13 08:36:44,759 INFO 2024-11-13 07:36:27 GMT 10s elapsed 2024-11-13 08:36:44,759 INFO ---- Requests ------------------------------------------------------------------ 2024-11-13 08:36:44,759 INFO > Global (OK=933 KO=0 ) 2024-11-13 08:36:44,759 INFO > restLoginInitiate (OK=79 KO=0 ) 2024-11-13 08:36:44,759 INFO > restLoginUsernamePasswordCallback (OK=77 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI-Post-Login - stage 1 (OK=16 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=16 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI AM idFromSession (OK=16 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI AM validateGoto (OK=16 KO=0 ) 2024-11-13 08:36:44,759 INFO > User Authorize PKCE stage (OK=90 KO=0 ) 2024-11-13 08:36:44,759 INFO > User AccessToken PKCE stage (OK=89 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI-Post-Login - stage 2 (OK=15 KO=0 ) 2024-11-13 08:36:44,759 INFO > Skip 2FA (OK=44 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=15 KO=0 ) 2024-11-13 08:36:44,759 INFO > UI Enduser appAuthHelper1 (OK=15 KO=0 ) 2024-11-13 08:36:44,759 INFO [...] 2024-11-13 08:36:44,767 INFO headers: 2024-11-13 08:36:44,767 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 08:36:44,767 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-13 08:36:44,767 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTA0YTk3OS0yYjVhLTRhZTItOWI3ZC0yYmQ4ZmExOTI2YWEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYTA2NGI3YzctNmJiYS00Y2MwLTg3ZTgtM2RhY2IxODliOTNkLTE3NTE0NTcyIiwic3VibmFtZSI6ImM5MDRhOTc5LTJiNWEtNGFlMi05YjdkLTJiZDhmYTE5MjZhYSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImYyczlldGJ0d0tPbnlZQ0FnYTRyOHJTamlQRSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ4Njk3NywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ4Njk3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTA1NzcsImlhdCI6MTczMTQ4Njk3NywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiaFZwRUZHMUI3Uy1aSjRfVkVxLVJXVjd6dnhBIn0.5CLlUo7hNzEJA0QHpoorsUQD_Q_ZssP5GdAw9GvARLI 2024-11-13 08:36:44,767 INFO Accept-Api-Version: 2024-11-13 08:36:44,767 INFO accept: */* 2024-11-13 08:36:44,767 INFO cookie: amlbcookie=01; 9b75a55744995d0=QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-13 08:36:44,767 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-13 08:36:44,767 INFO cookies: 2024-11-13 08:36:44,767 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 08:36:44,767 INFO 9b75a55744995d0=QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 08:36:44,767 INFO ========================= 2024-11-13 08:36:44,767 INFO HTTP response: 2024-11-13 08:36:44,767 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-13 08:36:44,767 INFO 08:36:17.399 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM version' failed for user 179282: j.i.IOException: Premature close 2024-11-13 08:36:44,767 INFO 08:36:17.399 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-13 08:36:44,767 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-13 08:36:44,767 INFO Request: 2024-11-13 08:36:44,767 INFO UI IDM version: KO j.i.IOException: Premature close 2024-11-13 08:36:44,767 INFO ========================= 2024-11-13 08:36:44,767 INFO Session: 2024-11-13 08:36:44,767 INFO Session(idc.benchmark.Mix2024,179282,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTA0YTk3OS0yYjVhLTRhZTItOWI3ZC0yYmQ4ZmExOTI2YWEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYTA2NGI3YzctNmJiYS00Y2MwLTg3ZTgtM2RhY2IxODliOTNkLTE3NTE0MDc2Iiwic3VibmFtZSI6ImM5MDRhOTc5LTJiNWEtNGFlMi05YjdkLTJiZDhmYTE5MjZhYSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6Inh0cHFTOEFsV0Y5dllmU0J0Q1Jyb0kxd3ZubyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ4Njk3NywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ4Njk3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTA1NzcsImlhdCI6MTczMTQ4Njk3NywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiVVZXbUV2Y29RcjZ3QjZTYVJRR0wyS2o4b2hZIn0.J4NJ6HZEmF1RjuGrW9yc2moyjGVSxUWOgUtsh-LKkFU, username -> user.59488, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTA0YTk3OS0yYjVhLTRhZTItOWI3ZC0yYmQ4ZmExOTI2YWEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYTA2NGI3YzctNmJiYS00Y2MwLTg3ZTgtM2RhY2IxODliOTNkLTE3NTE0NTcyIiwic3VibmFtZSI6ImM5MDRhOTc5LTJiNWEtNGFlMi05YjdkLTJiZDhmYTE5MjZhYSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImYyczlldGJ0d0tPbnlZQ0FnYTRyOHJTamlQRSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ4Njk3NywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ4Njk3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTA1NzcsImlhdCI6MTczMTQ4Njk3NywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiaFZwRUZHMUI3Uy1aSjRfVkVxLVJXVjd6dnhBIn0.5CLlUo7hNzEJA0QHpoorsUQD_Q_ZssP5GdAw9GvARLI, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@68d0baac, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> osTWchoBDYUZouuEljzJbXH4KLc, callbacks -> {"tokenId":"QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731486976789), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731486976789))), user_uuid -> c904a979-2b5a-4ae2-9b7d-2bd8fa1926aa, password -> Pa_ssw0rd, challenge -> l7VxQpX6qkmBXfMrUhNH3Yu8Z3awT4N1ytNnURHvCD8, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 201.29.34.102, verifier -> LTg5MDUyMzkxMzY3OTQ3MDM3NzI, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@71ac2b67, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@50fd3d90, tokenId -> QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.3),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007fe0d069a0e0@3afc0d71,io.netty.channel.epoll.EpollEventLoop@14f5da2c) 2024-11-13 08:36:44,767 INFO ========================= 2024-11-13 08:36:44,767 INFO HTTP request: 2024-11-13 08:36:44,768 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/version 2024-11-13 08:36:44,768 INFO headers: 2024-11-13 08:36:44,768 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 08:36:44,768 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-13 08:36:44,768 INFO Accept-Api-Version: 2024-11-13 08:36:44,768 INFO accept: */* 2024-11-13 08:36:44,768 INFO cookie: amlbcookie=01; 9b75a55744995d0=QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-13 08:36:44,768 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-13 08:36:44,768 INFO cookies: 2024-11-13 08:36:44,768 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 08:36:44,768 INFO 9b75a55744995d0=QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 08:36:44,768 INFO ========================= 2024-11-13 08:36:44,768 INFO HTTP response: 2024-11-13 08:36:44,768 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-13 08:36:44,768 INFO 08:36:17.399 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 179282: j.i.IOException: Premature close 2024-11-13 08:36:44,768 INFO 08:36:17.399 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-13 08:36:44,768 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-13 08:36:44,768 INFO Request: 2024-11-13 08:36:44,768 INFO UI IDM login: KO j.i.IOException: Premature close 2024-11-13 08:36:44,768 INFO ========================= 2024-11-13 08:36:44,768 INFO Session: 2024-11-13 08:36:44,768 INFO Session(idc.benchmark.Mix2024,179282,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTA0YTk3OS0yYjVhLTRhZTItOWI3ZC0yYmQ4ZmExOTI2YWEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYTA2NGI3YzctNmJiYS00Y2MwLTg3ZTgtM2RhY2IxODliOTNkLTE3NTE0MDc2Iiwic3VibmFtZSI6ImM5MDRhOTc5LTJiNWEtNGFlMi05YjdkLTJiZDhmYTE5MjZhYSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6Inh0cHFTOEFsV0Y5dllmU0J0Q1Jyb0kxd3ZubyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ4Njk3NywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ4Njk3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTA1NzcsImlhdCI6MTczMTQ4Njk3NywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiVVZXbUV2Y29RcjZ3QjZTYVJRR0wyS2o4b2hZIn0.J4NJ6HZEmF1RjuGrW9yc2moyjGVSxUWOgUtsh-LKkFU, username -> user.59488, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTA0YTk3OS0yYjVhLTRhZTItOWI3ZC0yYmQ4ZmExOTI2YWEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYTA2NGI3YzctNmJiYS00Y2MwLTg3ZTgtM2RhY2IxODliOTNkLTE3NTE0NTcyIiwic3VibmFtZSI6ImM5MDRhOTc5LTJiNWEtNGFlMi05YjdkLTJiZDhmYTE5MjZhYSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImYyczlldGJ0d0tPbnlZQ0FnYTRyOHJTamlQRSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ4Njk3NywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ4Njk3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTA1NzcsImlhdCI6MTczMTQ4Njk3NywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiaFZwRUZHMUI3Uy1aSjRfVkVxLVJXVjd6dnhBIn0.5CLlUo7hNzEJA0QHpoorsUQD_Q_ZssP5GdAw9GvARLI, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@68d0baac, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> osTWchoBDYUZouuEljzJbXH4KLc, callbacks -> {"tokenId":"QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731486976789), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731486976789))), user_uuid -> c904a979-2b5a-4ae2-9b7d-2bd8fa1926aa, password -> Pa_ssw0rd, challenge -> l7VxQpX6qkmBXfMrUhNH3Yu8Z3awT4N1ytNnURHvCD8, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 201.29.34.102, verifier -> LTg5MDUyMzkxMzY3OTQ3MDM3NzI, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@71ac2b67, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@50fd3d90, tokenId -> QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.3),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007fe0d069a0e0@3afc0d71,io.netty.channel.epoll.EpollEventLoop@14f5da2c) 2024-11-13 08:36:44,768 INFO ========================= 2024-11-13 08:36:44,768 INFO HTTP request: 2024-11-13 08:36:44,768 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-13 08:36:44,768 INFO headers: 2024-11-13 08:36:44,768 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 08:36:44,768 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-13 08:36:44,768 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTA0YTk3OS0yYjVhLTRhZTItOWI3ZC0yYmQ4ZmExOTI2YWEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYTA2NGI3YzctNmJiYS00Y2MwLTg3ZTgtM2RhY2IxODliOTNkLTE3NTE0NTcyIiwic3VibmFtZSI6ImM5MDRhOTc5LTJiNWEtNGFlMi05YjdkLTJiZDhmYTE5MjZhYSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImYyczlldGJ0d0tPbnlZQ0FnYTRyOHJTamlQRSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ4Njk3NywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ4Njk3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTA1NzcsImlhdCI6MTczMTQ4Njk3NywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiaFZwRUZHMUI3Uy1aSjRfVkVxLVJXVjd6dnhBIn0.5CLlUo7hNzEJA0QHpoorsUQD_Q_ZssP5GdAw9GvARLI 2024-11-13 08:36:44,768 INFO Accept-Api-Version: 2024-11-13 08:36:44,768 INFO accept: */* 2024-11-13 08:36:44,768 INFO cookie: amlbcookie=01; 9b75a55744995d0=QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-13 08:36:44,768 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-13 08:36:44,768 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-13 08:36:44,768 INFO content-length: 0 2024-11-13 08:36:44,768 INFO cookies: 2024-11-13 08:36:44,768 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 08:36:44,768 INFO 9b75a55744995d0=QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 08:36:44,768 INFO ========================= 2024-11-13 08:36:44,768 INFO HTTP response: 2024-11-13 08:36:44,768 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-13 08:36:44,768 INFO 08:36:17.400 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM privilege' failed for user 179282: j.i.IOException: Premature close 2024-11-13 08:36:44,768 INFO 08:36:17.400 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-13 08:36:44,768 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-13 08:36:44,768 INFO Request: 2024-11-13 08:36:44,768 INFO UI IDM privilege: KO j.i.IOException: Premature close 2024-11-13 08:36:44,768 INFO ========================= 2024-11-13 08:36:44,768 INFO Session: 2024-11-13 08:36:44,768 INFO Session(idc.benchmark.Mix2024,179282,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTA0YTk3OS0yYjVhLTRhZTItOWI3ZC0yYmQ4ZmExOTI2YWEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYTA2NGI3YzctNmJiYS00Y2MwLTg3ZTgtM2RhY2IxODliOTNkLTE3NTE0MDc2Iiwic3VibmFtZSI6ImM5MDRhOTc5LTJiNWEtNGFlMi05YjdkLTJiZDhmYTE5MjZhYSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6Inh0cHFTOEFsV0Y5dllmU0J0Q1Jyb0kxd3ZubyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ4Njk3NywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ4Njk3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTA1NzcsImlhdCI6MTczMTQ4Njk3NywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiVVZXbUV2Y29RcjZ3QjZTYVJRR0wyS2o4b2hZIn0.J4NJ6HZEmF1RjuGrW9yc2moyjGVSxUWOgUtsh-LKkFU, username -> user.59488, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTA0YTk3OS0yYjVhLTRhZTItOWI3ZC0yYmQ4ZmExOTI2YWEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYTA2NGI3YzctNmJiYS00Y2MwLTg3ZTgtM2RhY2IxODliOTNkLTE3NTE0NTcyIiwic3VibmFtZSI6ImM5MDRhOTc5LTJiNWEtNGFlMi05YjdkLTJiZDhmYTE5MjZhYSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImYyczlldGJ0d0tPbnlZQ0FnYTRyOHJTamlQRSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ4Njk3NywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ4Njk3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTA1NzcsImlhdCI6MTczMTQ4Njk3NywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiaFZwRUZHMUI3Uy1aSjRfVkVxLVJXVjd6dnhBIn0.5CLlUo7hNzEJA0QHpoorsUQD_Q_ZssP5GdAw9GvARLI, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@68d0baac, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> osTWchoBDYUZouuEljzJbXH4KLc, callbacks -> {"tokenId":"QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731486976789), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731486976789))), user_uuid -> c904a979-2b5a-4ae2-9b7d-2bd8fa1926aa, password -> Pa_ssw0rd, challenge -> l7VxQpX6qkmBXfMrUhNH3Yu8Z3awT4N1ytNnURHvCD8, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 201.29.34.102, verifier -> LTg5MDUyMzkxMzY3OTQ3MDM3NzI, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@71ac2b67, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@50fd3d90, tokenId -> QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.3),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007fe0d069a0e0@3afc0d71,io.netty.channel.epoll.EpollEventLoop@14f5da2c) 2024-11-13 08:36:44,768 INFO ========================= 2024-11-13 08:36:44,768 INFO HTTP request: 2024-11-13 08:36:44,768 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/privilege?_action=listPrivileges 2024-11-13 08:36:44,768 INFO headers: 2024-11-13 08:36:44,768 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 08:36:44,769 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-13 08:36:44,769 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTA0YTk3OS0yYjVhLTRhZTItOWI3ZC0yYmQ4ZmExOTI2YWEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYTA2NGI3YzctNmJiYS00Y2MwLTg3ZTgtM2RhY2IxODliOTNkLTE3NTE0NTcyIiwic3VibmFtZSI6ImM5MDRhOTc5LTJiNWEtNGFlMi05YjdkLTJiZDhmYTE5MjZhYSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImYyczlldGJ0d0tPbnlZQ0FnYTRyOHJTamlQRSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTQ4Njk3NywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTQ4Njk3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE0OTA1NzcsImlhdCI6MTczMTQ4Njk3NywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiaFZwRUZHMUI3Uy1aSjRfVkVxLVJXVjd6dnhBIn0.5CLlUo7hNzEJA0QHpoorsUQD_Q_ZssP5GdAw9GvARLI 2024-11-13 08:36:44,769 INFO Accept-Api-Version: 2024-11-13 08:36:44,769 INFO accept: */* 2024-11-13 08:36:44,769 INFO cookie: amlbcookie=01; 9b75a55744995d0=QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-13 08:36:44,769 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-13 08:36:44,769 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-13 08:36:44,769 INFO content-length: 0 2024-11-13 08:36:44,769 INFO cookies: 2024-11-13 08:36:44,769 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 08:36:44,769 INFO 9b75a55744995d0=QmHqzrYfC6TtuflDdxSUxSaV7Cs.*AAJTSQACMDIAAlNLABwrajdlSEF2MU1aOVNYaE9sWFBicUJHNUN5QTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-13 08:36:44,769 INFO ========================= 2024-11-13 08:36:44,769 INFO HTTP response: 2024-11-13 08:36:44,769 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-13 08:36:44,769 INFO [INFO] ------------------------------------------------------------------------ 2024-11-13 08:36:44,769 INFO [INFO] BUILD SUCCESS 2024-11-13 08:36:44,769 INFO [INFO] ------------------------------------------------------------------------ 2024-11-13 08:36:44,769 INFO [INFO] Total time: 01:00 h 2024-11-13 08:36:44,769 INFO [INFO] Finished at: 2024-11-13T08:36:17Z 2024-11-13 08:36:44,769 INFO [INFO] ------------------------------------------------------------------------ 2024-11-13 08:36:44,769 INFO 2024-11-13 08:36:17,483 INFO Gatling simulation run ended successfully 2024-11-13 08:36:44,769 INFO 2024-11-13 08:36:17,483 DEBUG No archive name provided. No archive will be created 2024-11-13 08:36:44,769 INFO ________________________________________________________________________________ [2024-11-13 08:36:44] benchmark-50 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-13 08:36:44,769 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-50/stderr.txt is small (0 lines), printing all lines: 2024-11-13 08:36:44,769 DEBUG ----- output ----- 2024-11-13 08:36:44,769 DEBUG 2024-11-13 08:36:44,769 INFO ________________________________________________________________________________ [2024-11-13 08:36:44] benchmark-50 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 08:36:44] benchmark-50 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-13 08:36:44] benchmark-50 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped