--Task-- name: benchmark-120 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-90'] preceding_task: benchmark-90 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 120} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2025-01-16 21:38:07] benchmark-120 pre : N/A ________________________________________________________________________________ 2025-01-16 21:38:07,069 INFO Lodestar assumed that full stack is deployed so will set all products host URLs in Gatling system properties 2025-01-16 21:38:07 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2025-01-16 21:38:07 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-120/stdout.txt Pod log : /results/orders/benchmark-120 2025-01-16 21:38:07,069 INFO 2025-01-16 21:38:07,069 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 2025-01-16 21:38:07,069 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 21:38:07,346 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 21:38:07,346 INFO [loop_until]: OK (rc = 0) 2025-01-16 21:38:07,346 DEBUG --- stdout --- 2025-01-16 21:38:07,346 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 21:38:07,346 DEBUG --- stderr --- 2025-01-16 21:38:07,346 DEBUG ________________________________________________________________________________ [2025-01-16 21:38:07] benchmark-120 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2025-01-16 21:38:07,346 INFO 2025-01-16 21:38:07,346 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 2025-01-16 21:38:07,346 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 21:38:07,622 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 21:38:07,622 INFO [loop_until]: OK (rc = 0) 2025-01-16 21:38:07,622 DEBUG --- stdout --- 2025-01-16 21:38:07,622 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 21:38:07,622 DEBUG --- stderr --- 2025-01-16 21:38:07,622 DEBUG /lodestar/lodestar.py perf run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-200m" --results-folder="/results/gatling/benchmark-120" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-200m.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-perf-200m.forgeblocks.com -Dlogin_cookie=15892ffd844ef80 -Dnum_users=200000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=120 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2025-01-16 21:38:07] benchmark-120 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2025-01-16 21:38:07,622 INFO 2025-01-16 21:38:07,622 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 2025-01-16 21:38:07,622 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 21:38:07,897 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 21:38:07,897 INFO [loop_until]: OK (rc = 0) 2025-01-16 21:38:07,897 DEBUG --- stdout --- 2025-01-16 21:38:07,897 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 21:38:07,897 DEBUG --- stderr --- 2025-01-16 21:38:07,897 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/order.json { "name": "benchmark-120", "command": " /lodestar/lodestar.py perf run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-200m\" --results-folder=\"/results/gatling/benchmark-120\" --no-reports --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx4G\" --simulation-system-properties=\"-Dam_host=openam-perf-200m.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-perf-200m.forgeblocks.com -Dlogin_cookie=15892ffd844ef80 -Dnum_users=200000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=120 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-120" } ________________________________________________________________________________ [2025-01-16 21:38:07] benchmark-120 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: ________________________________________________________________________________ 2025-01-16 21:38:07,900 INFO 2025-01-16 21:38:07,900 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-01-16 21:38:07,900 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2025-01-16 21:38:08,118 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-01-16 21:38:08,118 INFO [loop_until]: OK (rc = 0) 2025-01-16 21:38:08,118 DEBUG --- stdout --- 2025-01-16 21:38:08,118 DEBUG WAITING 2025-01-16 21:38:08,118 DEBUG --- stderr --- 2025-01-16 21:38:08,118 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 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2025-01-16 21:38:08] benchmark-120 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2025-01-16 21:38:08,119 INFO 2025-01-16 21:38:08,119 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 2025-01-16 21:38:08,119 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 21:38:08,395 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 21:38:08,395 INFO [loop_until]: OK (rc = 0) 2025-01-16 21:38:08,395 DEBUG --- stdout --- 2025-01-16 21:38:08,395 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 21:38:08,395 DEBUG --- stderr --- 2025-01-16 21:38:08,395 DEBUG 2025-01-16 21:38:08,396 INFO 2025-01-16 21:38:08,396 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-120/order.json overseer-0-5865b977f-pk2tz:/results/orders/order.json 2025-01-16 21:38:08,396 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-01-16 21:38:09,811 INFO [loop_until]: OK (rc = 0) 2025-01-16 21:38:09,812 DEBUG --- stdout --- 2025-01-16 21:38:09,812 DEBUG 2025-01-16 21:38:09,812 DEBUG --- stderr --- 2025-01-16 21:38:09,812 DEBUG Order has been successfully forwarded to overseer 2025-01-16 21:38:09 - INFO: Check if Gatling simulation started successfully 2025-01-16 21:38:09,812 INFO 2025-01-16 21:38:09,812 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 2025-01-16 21:38:09,812 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 21:38:10,087 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 21:38:10,087 INFO [loop_until]: OK (rc = 0) 2025-01-16 21:38:10,087 DEBUG --- stdout --- 2025-01-16 21:38:10,087 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 21:38:10,087 DEBUG --- stderr --- 2025-01-16 21:38:10,087 DEBUG 2025-01-16 21:38:10,088 INFO 2025-01-16 21:38:10,088 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5865b977f-pk2tz -- head --lines 5000 /results/orders/benchmark-120/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2025-01-16 21:38:10,088 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2025-01-16 21:38:10,830 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2025-01-16 21:38:16,567 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2025-01-16 21:38:22,304 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2025-01-16 21:38:28,038 INFO [loop_until]: Function succeeded after 17s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2025-01-16 21:38:33,778 INFO [loop_until]: Function succeeded after 23s (rc=0) - expected pattern found 2025-01-16 21:38:33,778 INFO [loop_until]: OK (rc = 0) 2025-01-16 21:38:33,778 DEBUG --- stdout --- 2025-01-16 21:38:33,778 DEBUG 2025-01-16 21:38:10,720 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmptp4fr4vq 2025-01-16 21:38:10,723 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-120" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-200m.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-perf-200m.forgeblocks.com -Dlogin_cookie=15892ffd844ef80 -Dnum_users=200000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=120 -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/tmptp4fr4vq/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/tmptp4fr4vq/target/test-classes ... [INFO] compile in 12.4 s [INFO] [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< [INFO] [INFO] [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- [INFO] Running simulation com.forgerock.pyrock.Loadtest. Gatling 3.13.1 is available! (you're using 3.12.0) System property think_time_multiplier not set. No think time emulation will be done in the simulation. Simulation com.forgerock.pyrock.Loadtest started... 2025-01-16 21:38:33,778 DEBUG --- stderr --- 2025-01-16 21:38:33,778 DEBUG ________________________________________________________________________________ [2025-01-16 21:38:33] benchmark-120 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 07s, 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) ________________________________________________________________________________ [2025-01-16 22:38:42] benchmark-120 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2025-01-16 22:38:42,822 INFO 2025-01-16 22:38:42,822 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2025-01-16 22:38:42,822 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:43,128 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:43,128 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:43,128 DEBUG --- stdout --- 2025-01-16 22:38:43,128 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:43,128 DEBUG --- stderr --- 2025-01-16 22:38:43,128 DEBUG 2025-01-16 22:38:43,128 INFO 2025-01-16 22:38:43,128 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5865b977f-pk2tz -- rm -f /results/orders/order.json 2025-01-16 22:38:43,128 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-01-16 22:38:43,914 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:43,914 DEBUG --- stdout --- 2025-01-16 22:38:43,914 DEBUG 2025-01-16 22:38:43,914 DEBUG --- stderr --- 2025-01-16 22:38:43,914 DEBUG Order ran during 1h ________________________________________________________________________________ [2025-01-16 22:38:43] benchmark-120 download_logs : Download individual order files ________________________________________________________________________________ 2025-01-16 22:38:43 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/order.json 2025-01-16 22:38:43,915 INFO 2025-01-16 22:38:43,915 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 2025-01-16 22:38:43,915 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:44,190 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:44,190 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:44,190 DEBUG --- stdout --- 2025-01-16 22:38:44,190 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:44,190 DEBUG --- stderr --- 2025-01-16 22:38:44,190 DEBUG Checking pod: overseer-0-5865b977f-pk2tz for file exists, if not wait. 2025-01-16 22:38:44,190 INFO 2025-01-16 22:38:44,190 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 2025-01-16 22:38:44,190 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:44,468 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:44,468 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:44,468 DEBUG --- stdout --- 2025-01-16 22:38:44,468 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:44,468 DEBUG --- stderr --- 2025-01-16 22:38:44,468 DEBUG 2025-01-16 22:38:44,469 INFO 2025-01-16 22:38:44,469 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5865b977f-pk2tz --stdin -- bash -c 'if [ -f "/results/orders/benchmark-120/rc.txt" ]; then echo "/results/orders/benchmark-120/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-120/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-120/rc.txt file found" 2025-01-16 22:38:44,469 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-01-16 22:38:45,271 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-01-16 22:38:45,271 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:45,271 DEBUG --- stdout --- 2025-01-16 22:38:45,271 DEBUG /results/orders/benchmark-120/rc.txt file found 2025-01-16 22:38:45,271 DEBUG --- stderr --- 2025-01-16 22:38:45,271 DEBUG 2025-01-16 22:38:45,271 INFO 2025-01-16 22:38:45,271 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 2025-01-16 22:38:45,271 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:45,545 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:45,545 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:45,545 DEBUG --- stdout --- 2025-01-16 22:38:45,545 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:45,545 DEBUG --- stderr --- 2025-01-16 22:38:45,545 DEBUG 2025-01-16 22:38:45,545 INFO 2025-01-16 22:38:45,545 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5865b977f-pk2tz --stdin -- du -k /results/orders/benchmark-120/rc.txt 2025-01-16 22:38:46,352 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-01-16 22:38:46,352 DEBUG --- stdout --- 2025-01-16 22:38:46,352 DEBUG 4 /results/orders/benchmark-120/rc.txt 2025-01-16 22:38:46,352 DEBUG --- stderr --- 2025-01-16 22:38:46,352 DEBUG 2025-01-16 22:38:46,352 INFO - Download file (size 4k) 2025-01-16 22:38:46,353 INFO 2025-01-16 22:38:46,353 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 2025-01-16 22:38:46,353 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:46,628 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:46,628 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:46,628 DEBUG --- stdout --- 2025-01-16 22:38:46,628 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:46,628 DEBUG --- stderr --- 2025-01-16 22:38:46,628 DEBUG source : /results/orders/benchmark-120/rc.txt @ overseer-0-5865b977f-pk2tz destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/rc.txt 2025-01-16 22:38:46,628 INFO 2025-01-16 22:38:46,628 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 2025-01-16 22:38:46,628 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:46,903 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:46,903 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:46,903 DEBUG --- stdout --- 2025-01-16 22:38:46,903 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:46,903 DEBUG --- stderr --- 2025-01-16 22:38:46,903 DEBUG 2025-01-16 22:38:46,904 INFO 2025-01-16 22:38:46,904 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5865b977f-pk2tz:/results/orders/benchmark-120/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/rc.txt 2025-01-16 22:38:46,904 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-01-16 22:38:47,639 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:47,639 DEBUG --- stdout --- 2025-01-16 22:38:47,639 DEBUG tar: Removing leading `/' from member names 2025-01-16 22:38:47,639 DEBUG --- stderr --- 2025-01-16 22:38:47,639 DEBUG 2025-01-16 22:38:47,640 INFO 2025-01-16 22:38:47,640 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 2025-01-16 22:38:47,640 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:47,915 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:47,915 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:47,915 DEBUG --- stdout --- 2025-01-16 22:38:47,915 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:47,915 DEBUG --- stderr --- 2025-01-16 22:38:47,915 DEBUG Checking pod: overseer-0-5865b977f-pk2tz for file exists, if not wait. 2025-01-16 22:38:47,915 INFO 2025-01-16 22:38:47,915 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 2025-01-16 22:38:47,915 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:48,190 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:48,190 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:48,190 DEBUG --- stdout --- 2025-01-16 22:38:48,190 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:48,190 DEBUG --- stderr --- 2025-01-16 22:38:48,190 DEBUG 2025-01-16 22:38:48,191 INFO 2025-01-16 22:38:48,191 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5865b977f-pk2tz --stdin -- bash -c 'if [ -f "/results/orders/benchmark-120/stderr.txt" ]; then echo "/results/orders/benchmark-120/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-120/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-120/stderr.txt file found" 2025-01-16 22:38:48,191 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-01-16 22:38:48,997 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-01-16 22:38:48,997 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:48,997 DEBUG --- stdout --- 2025-01-16 22:38:48,997 DEBUG /results/orders/benchmark-120/stderr.txt file found 2025-01-16 22:38:48,997 DEBUG --- stderr --- 2025-01-16 22:38:48,997 DEBUG 2025-01-16 22:38:48,997 INFO 2025-01-16 22:38:48,997 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 2025-01-16 22:38:48,997 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:49,314 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:49,314 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:49,314 DEBUG --- stdout --- 2025-01-16 22:38:49,314 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:49,314 DEBUG --- stderr --- 2025-01-16 22:38:49,314 DEBUG 2025-01-16 22:38:49,314 INFO 2025-01-16 22:38:49,314 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5865b977f-pk2tz --stdin -- du -k /results/orders/benchmark-120/stderr.txt 2025-01-16 22:38:50,124 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-01-16 22:38:50,124 DEBUG --- stdout --- 2025-01-16 22:38:50,124 DEBUG 0 /results/orders/benchmark-120/stderr.txt 2025-01-16 22:38:50,124 DEBUG --- stderr --- 2025-01-16 22:38:50,124 DEBUG 2025-01-16 22:38:50,124 INFO - Download file (size 0k) 2025-01-16 22:38:50,125 INFO 2025-01-16 22:38:50,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 2025-01-16 22:38:50,125 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:50,400 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:50,400 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:50,400 DEBUG --- stdout --- 2025-01-16 22:38:50,400 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:50,400 DEBUG --- stderr --- 2025-01-16 22:38:50,400 DEBUG source : /results/orders/benchmark-120/stderr.txt @ overseer-0-5865b977f-pk2tz destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/stderr.txt 2025-01-16 22:38:50,400 INFO 2025-01-16 22:38:50,400 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 2025-01-16 22:38:50,400 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:50,675 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:50,675 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:50,675 DEBUG --- stdout --- 2025-01-16 22:38:50,675 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:50,675 DEBUG --- stderr --- 2025-01-16 22:38:50,675 DEBUG 2025-01-16 22:38:50,676 INFO 2025-01-16 22:38:50,676 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5865b977f-pk2tz:/results/orders/benchmark-120/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/stderr.txt 2025-01-16 22:38:50,676 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-01-16 22:38:51,432 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:51,432 DEBUG --- stdout --- 2025-01-16 22:38:51,432 DEBUG tar: Removing leading `/' from member names 2025-01-16 22:38:51,432 DEBUG --- stderr --- 2025-01-16 22:38:51,432 DEBUG 2025-01-16 22:38:51,432 INFO 2025-01-16 22:38:51,432 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 2025-01-16 22:38:51,432 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:51,708 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:51,708 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:51,708 DEBUG --- stdout --- 2025-01-16 22:38:51,708 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:51,708 DEBUG --- stderr --- 2025-01-16 22:38:51,708 DEBUG Checking pod: overseer-0-5865b977f-pk2tz for file exists, if not wait. 2025-01-16 22:38:51,709 INFO 2025-01-16 22:38:51,709 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 2025-01-16 22:38:51,709 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:51,983 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:51,983 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:51,983 DEBUG --- stdout --- 2025-01-16 22:38:51,983 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:51,983 DEBUG --- stderr --- 2025-01-16 22:38:51,983 DEBUG 2025-01-16 22:38:51,984 INFO 2025-01-16 22:38:51,984 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5865b977f-pk2tz --stdin -- bash -c 'if [ -f "/results/orders/benchmark-120/stdout.txt" ]; then echo "/results/orders/benchmark-120/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-120/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-120/stdout.txt file found" 2025-01-16 22:38:51,984 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-01-16 22:38:52,782 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-01-16 22:38:52,782 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:52,782 DEBUG --- stdout --- 2025-01-16 22:38:52,782 DEBUG /results/orders/benchmark-120/stdout.txt file found 2025-01-16 22:38:52,782 DEBUG --- stderr --- 2025-01-16 22:38:52,782 DEBUG 2025-01-16 22:38:52,782 INFO 2025-01-16 22:38:52,782 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 2025-01-16 22:38:52,782 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:53,057 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:53,057 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:53,057 DEBUG --- stdout --- 2025-01-16 22:38:53,057 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:53,057 DEBUG --- stderr --- 2025-01-16 22:38:53,057 DEBUG 2025-01-16 22:38:53,057 INFO 2025-01-16 22:38:53,057 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5865b977f-pk2tz --stdin -- du -k /results/orders/benchmark-120/stdout.txt 2025-01-16 22:38:53,857 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-01-16 22:38:53,857 DEBUG --- stdout --- 2025-01-16 22:38:53,857 DEBUG 7308 /results/orders/benchmark-120/stdout.txt 2025-01-16 22:38:53,857 DEBUG --- stderr --- 2025-01-16 22:38:53,857 DEBUG 2025-01-16 22:38:53,857 INFO - Download file (size 7308k) 2025-01-16 22:38:53,858 INFO 2025-01-16 22:38:53,858 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 2025-01-16 22:38:53,858 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:54,132 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:54,132 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:54,132 DEBUG --- stdout --- 2025-01-16 22:38:54,132 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:54,132 DEBUG --- stderr --- 2025-01-16 22:38:54,132 DEBUG source : /results/orders/benchmark-120/stdout.txt @ overseer-0-5865b977f-pk2tz destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/stdout.txt 2025-01-16 22:38:54,132 INFO 2025-01-16 22:38:54,132 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 2025-01-16 22:38:54,132 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:54,407 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:54,407 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:54,407 DEBUG --- stdout --- 2025-01-16 22:38:54,407 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:54,407 DEBUG --- stderr --- 2025-01-16 22:38:54,407 DEBUG 2025-01-16 22:38:54,407 INFO 2025-01-16 22:38:54,407 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5865b977f-pk2tz:/results/orders/benchmark-120/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/stdout.txt 2025-01-16 22:38:54,407 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-01-16 22:38:55,742 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:55,742 DEBUG --- stdout --- 2025-01-16 22:38:55,742 DEBUG tar: Removing leading `/' from member names 2025-01-16 22:38:55,742 DEBUG --- stderr --- 2025-01-16 22:38:55,742 DEBUG ________________________________________________________________________________ [2025-01-16 22:38:55] benchmark-120 show_cmd : Display order command ________________________________________________________________________________ 2025-01-16 22:38:55,742 INFO 2025-01-16 22:38:55,742 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 2025-01-16 22:38:55,742 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:56,017 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:56,017 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:56,017 DEBUG --- stdout --- 2025-01-16 22:38:56,017 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:56,017 DEBUG --- stderr --- 2025-01-16 22:38:56,017 DEBUG Checking pod: overseer-0-5865b977f-pk2tz for file exists, if not wait. 2025-01-16 22:38:56,018 INFO 2025-01-16 22:38:56,018 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 2025-01-16 22:38:56,018 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:56,292 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:56,292 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:56,292 DEBUG --- stdout --- 2025-01-16 22:38:56,292 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:56,292 DEBUG --- stderr --- 2025-01-16 22:38:56,292 DEBUG 2025-01-16 22:38:56,293 INFO 2025-01-16 22:38:56,293 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5865b977f-pk2tz --stdin -- bash -c 'if [ -f "/results/orders/benchmark-120/cmd.txt" ]; then echo "/results/orders/benchmark-120/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-120/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-120/cmd.txt file found" 2025-01-16 22:38:56,293 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-01-16 22:38:57,090 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-01-16 22:38:57,090 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:57,090 DEBUG --- stdout --- 2025-01-16 22:38:57,090 DEBUG /results/orders/benchmark-120/cmd.txt file found 2025-01-16 22:38:57,090 DEBUG --- stderr --- 2025-01-16 22:38:57,090 DEBUG 2025-01-16 22:38:57,090 INFO 2025-01-16 22:38:57,090 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 2025-01-16 22:38:57,091 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:57,364 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:57,364 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:57,364 DEBUG --- stdout --- 2025-01-16 22:38:57,364 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:57,364 DEBUG --- stderr --- 2025-01-16 22:38:57,364 DEBUG 2025-01-16 22:38:57,364 INFO 2025-01-16 22:38:57,365 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5865b977f-pk2tz --stdin -- du -k /results/orders/benchmark-120/cmd.txt 2025-01-16 22:38:58,178 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-01-16 22:38:58,178 DEBUG --- stdout --- 2025-01-16 22:38:58,178 DEBUG 4 /results/orders/benchmark-120/cmd.txt 2025-01-16 22:38:58,178 DEBUG --- stderr --- 2025-01-16 22:38:58,178 DEBUG 2025-01-16 22:38:58,178 INFO - Download file (size 4k) 2025-01-16 22:38:58,178 INFO 2025-01-16 22:38:58,178 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 2025-01-16 22:38:58,178 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:58,453 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:58,453 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:58,453 DEBUG --- stdout --- 2025-01-16 22:38:58,453 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:58,453 DEBUG --- stderr --- 2025-01-16 22:38:58,453 DEBUG source : /results/orders/benchmark-120/cmd.txt @ overseer-0-5865b977f-pk2tz destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/cmd.txt 2025-01-16 22:38:58,454 INFO 2025-01-16 22:38:58,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 2025-01-16 22:38:58,454 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-01-16 22:38:58,728 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-01-16 22:38:58,728 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:58,728 DEBUG --- stdout --- 2025-01-16 22:38:58,728 DEBUG overseer-0-5865b977f-pk2tz 2025-01-16 22:38:58,728 DEBUG --- stderr --- 2025-01-16 22:38:58,728 DEBUG 2025-01-16 22:38:58,729 INFO 2025-01-16 22:38:58,729 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5865b977f-pk2tz:/results/orders/benchmark-120/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/cmd.txt 2025-01-16 22:38:58,729 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-01-16 22:38:59,484 INFO [loop_until]: OK (rc = 0) 2025-01-16 22:38:59,484 DEBUG --- stdout --- 2025-01-16 22:38:59,484 DEBUG tar: Removing leading `/' from member names 2025-01-16 22:38:59,484 DEBUG --- stderr --- 2025-01-16 22:38:59,484 DEBUG 2025-01-16 22:38:59,484 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/cmd.txt is small (1 lines), printing all lines: 2025-01-16 22:38:59,484 DEBUG ----- output ----- 2025-01-16 22:38:59,484 DEBUG /lodestar/lodestar.py perf run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-200m" --results-folder="/results/gatling/benchmark-120" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-200m.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-perf-200m.forgeblocks.com -Dlogin_cookie=15892ffd844ef80 -Dnum_users=200000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=120 -Duser_password=Pa_ssw0rd" 2025-01-16 22:38:59,484 INFO ________________________________________________________________________________ [2025-01-16 22:38:59] benchmark-120 show_rc : Display rc order ________________________________________________________________________________ 2025-01-16 22:38:59,485 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/rc.txt is small (1 lines), printing all lines: 2025-01-16 22:38:59,485 DEBUG ----- output ----- 2025-01-16 22:38:59,485 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2025-01-16 22:38:59,485 INFO ________________________________________________________________________________ [2025-01-16 22:38:59] benchmark-120 show_stdout : Display stdout order ________________________________________________________________________________ 2025-01-16 22:38:59,495 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-120/stdout.txt is 82941 lines): 2025-01-16 22:38:59,495 DEBUG ----- output ----- 2025-01-16 22:38:59,495 INFO 2025-01-16 21:38:10,720 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmptp4fr4vq 2025-01-16 22:38:59,495 INFO 2025-01-16 21:38:10,723 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-120" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-200m.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-perf-200m.forgeblocks.com -Dlogin_cookie=15892ffd844ef80 -Dnum_users=200000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=120 -Duser_password=**** 2025-01-16 22:38:59,495 INFO [INFO] Scanning for projects... 2025-01-16 22:38:59,495 INFO [INFO] 2025-01-16 22:38:59,495 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2025-01-16 22:38:59,495 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2025-01-16 22:38:59,495 INFO [INFO] from pom.xml 2025-01-16 22:38:59,495 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2025-01-16 22:38:59,495 INFO [INFO] 2025-01-16 22:38:59,495 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2025-01-16 22:38:59,495 INFO [INFO] 2025-01-16 22:38:59,495 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2025-01-16 22:38:59,495 INFO [INFO] skip non existing resourceDirectory /tmp/tmptp4fr4vq/src/main/resources 2025-01-16 22:38:59,495 INFO [INFO] 2025-01-16 22:38:59,495 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2025-01-16 22:38:59,495 INFO [INFO] No sources to compile 2025-01-16 22:38:59,495 INFO [INFO] 2025-01-16 22:38:59,495 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2025-01-16 22:38:59,495 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2025-01-16 22:38:59,495 INFO [INFO] 2025-01-16 22:38:59,495 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2025-01-16 22:38:59,495 INFO [INFO] Nothing to compile - all classes are up to date. 2025-01-16 22:38:59,495 INFO [INFO] 2025-01-16 22:38:59,495 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2025-01-16 22:38:59,495 INFO [INFO] compiling 38 Scala sources to /tmp/tmptp4fr4vq/target/test-classes ... 2025-01-16 22:38:59,495 INFO [INFO] compile in 12.4 s 2025-01-16 22:38:59,495 INFO [INFO] 2025-01-16 22:38:59,495 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2025-01-16 22:38:59,495 INFO [INFO] 2025-01-16 22:38:59,495 INFO [INFO] 2025-01-16 22:38:59,495 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2025-01-16 22:38:59,495 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2025-01-16 22:38:59,495 INFO Gatling 3.13.1 is available! (you're using 3.12.0) 2025-01-16 22:38:59,495 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2025-01-16 22:38:59,495 INFO Simulation com.forgerock.pyrock.Loadtest started... 2025-01-16 22:38:59,495 INFO 2025-01-16 22:38:59,495 INFO ================================================================================ 2025-01-16 22:38:59,495 INFO 2025-01-16 21:38:34 GMT 4s elapsed 2025-01-16 22:38:59,495 INFO ---- Requests ------------------------------------------------------------------ 2025-01-16 22:38:59,495 INFO > Global (OK=527 KO=0 ) 2025-01-16 22:38:59,495 INFO > restLoginInitiate (OK=48 KO=0 ) 2025-01-16 22:38:59,495 INFO > restLoginUsernamePasswordCallback (OK=47 KO=0 ) 2025-01-16 22:38:59,495 INFO > User Authorize PKCE stage (OK=53 KO=0 ) 2025-01-16 22:38:59,495 INFO > User AccessToken PKCE stage (OK=53 KO=0 ) 2025-01-16 22:38:59,495 INFO > tokenUserinfo (OK=9 KO=0 ) 2025-01-16 22:38:59,495 INFO > Skip 2FA (OK=27 KO=0 ) 2025-01-16 22:38:59,495 INFO > GetUUid (OK=14 KO=0 ) 2025-01-16 22:38:59,495 INFO > GetOpenidConfig (OK=14 KO=0 ) 2025-01-16 22:38:59,495 INFO > CreatePolicy (OK=14 KO=0 ) 2025-01-16 22:38:59,495 INFO > UI-Post-Login - stage 1 (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI AM idFromSession (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI AM validateGoto (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI-Post-Login - stage 2 (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI Enduser appAuthHelper1 (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI Enduser appAuthHelper2 (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > RootUserinfo (OK=16 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI-Post-Login - stage 3 (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI IDM uiconfig (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI Enduser sessioncheck (OK=9 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI IDM version (OK=8 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI IDM login (OK=8 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI IDM features (OK=8 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI IDM privilege (OK=8 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI IDM managed user schema (OK=7 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI IDM user (OK=7 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI IDM dashboard (OK=7 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI IDM oidcToken (OK=7 KO=0 ) 2025-01-16 22:38:59,496 INFO > userinfo (OK=7 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI-Logout (OK=7 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI-Logout Redirect 1 (OK=7 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI AM revoke1 (OK=7 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI AM revoke2 (OK=7 KO=0 ) 2025-01-16 22:38:59,496 INFO > UI AM endsession (OK=7 KO=0 ) 2025-01-16 22:38:59,496 INFO > getIdToken (OK=4 KO=0 ) 2025-01-16 22:38:59,496 INFO > getIdToken Redirect 1 (OK=4 KO=0 ) 2025-01-16 22:38:59,496 INFO > createOrganization (OK=4 KO=0 ) 2025-01-16 22:38:59,496 INFO > queryOrganization (OK=4 KO=0 ) 2025-01-16 22:38:59,496 INFO > deleteOrganization (OK=4 KO=0 ) 2025-01-16 22:38:59,496 INFO > loginTreeWorkflow_restLoginInitiate (OK=1 KO=0 ) 2025-01-16 22:38:59,496 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2025-01-16 22:38:59,496 INFO 2025-01-16 22:38:59,496 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2025-01-16 22:38:59,496 INFO [- ] 0% 2025-01-16 22:38:59,496 INFO waiting: 433441 / active: 29 / done: 21 2025-01-16 22:38:59,496 INFO ================================================================================ 2025-01-16 22:38:59,496 INFO 2025-01-16 22:38:59,496 INFO 2025-01-16 22:38:59,496 INFO ================================================================================ 2025-01-16 22:38:59,496 INFO 2025-01-16 21:38:39 GMT 9s elapsed 2025-01-16 22:38:59,496 INFO ---- Requests ------------------------------------------------------------------ 2025-01-16 22:38:59,496 INFO > Global (OK=2384 KO=0 ) 2025-01-16 22:38:59,496 INFO > restLoginInitiate (OK=190 KO=0 ) 2025-01-16 22:38:59,496 INFO > restLoginUsernamePasswordCallback (OK=187 KO=0 ) 2025-01-16 22:38:59,496 INFO > User Authorize PKCE stage (OK=223 KO=0 ) 2025-01-16 22:38:59,496 INFO > User AccessToken PKCE stage (OK=222 KO=0 ) 2025-01-16 22:38:59,496 INFO > tokenUserinfo (OK=38 KO=0 ) 2025-01-16 22:38:59,496 INFO > Skip 2FA (OK=102 KO=0 ) 2025-01-16 22:38:59,496 INFO [...] 2025-01-16 22:38:59,509 INFO headers: 2025-01-16 22:38:59,509 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 2025-01-16 22:38:59,509 INFO referer: https://openam-perf-200m.forgeblocks.com/am/ 2025-01-16 22:38:59,509 INFO Accept-Api-Version: 2025-01-16 22:38:59,509 INFO accept: */* 2025-01-16 22:38:59,509 INFO cookie: amlbcookie=01; 15892ffd844ef80=Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2025-01-16 22:38:59,509 INFO host: openam-perf-200m.forgeblocks.com 2025-01-16 22:38:59,509 INFO cookies: 2025-01-16 22:38:59,509 INFO amlbcookie=01, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2025-01-16 22:38:59,509 INFO 15892ffd844ef80=Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2025-01-16 22:38:59,509 INFO ========================= 2025-01-16 22:38:59,509 INFO HTTP response: 2025-01-16 22:38:59,509 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2025-01-16 22:38:59,509 INFO 22:38:29.056 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM privilege' failed for user 429739: j.i.IOException: Premature close 2025-01-16 22:38:59,509 INFO 22:38:29.056 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2025-01-16 22:38:59,509 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2025-01-16 22:38:59,509 INFO Request: 2025-01-16 22:38:59,509 INFO UI IDM privilege: KO j.i.IOException: Premature close 2025-01-16 22:38:59,509 INFO ========================= 2025-01-16 22:38:59,509 INFO Session: 2025-01-16 22:38:59,509 INFO Session(idc.benchmark.Mix2024,429739,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlOTA2Y2M3Ni0wNmMxLTQxZDItOGQ3NC0zMGVlMjg4MDM3NGEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZDU2YzQ3YzgtMjA2OC00YzQwLWIxZDktZTkxOTFlNGI4NDNmLTM3NjU1NjMwIiwic3VibmFtZSI6ImU5MDZjYzc2LTA2YzEtNDFkMi04ZDc0LTMwZWUyODgwMzc0YSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtMjAwbS5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJZcmpLenVBd3U0MHg4V2RQZEVOZkduY2Y5YkkiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzcwNjcxMDgsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzcwNjcxMDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzM3MDcwNzA4LCJpYXQiOjE3MzcwNjcxMDgsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6ImlJMzZZSERUb2tHa0lEYWJ2UFQ2WXVNeldySSJ9.1mG6AbUwRX-YPev-fWT4uzdmniigS_faTtpbUYR2Q68, username -> user.177090332, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlOTA2Y2M3Ni0wNmMxLTQxZDItOGQ3NC0zMGVlMjg4MDM3NGEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYWZlOTNlNzEtODljYy00OTcxLWEzZmQtNzkzNmQzOWRlYWJhLTM3ODAyMzA5Iiwic3VibmFtZSI6ImU5MDZjYzc2LTA2YzEtNDFkMi04ZDc0LTMwZWUyODgwMzc0YSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtMjAwbS5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiItSWk0b19VX0tnbmplZXBPMDBBYm1wRWNEM00iLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzcwNjcxMDgsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzcwNjcxMDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzM3MDcwNzA4LCJpYXQiOjE3MzcwNjcxMDgsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6ImNSOWR5QUtuTUZUci1yYjFPYXF2aktOOG1SbyJ9.uVWKLNWn0-mfwyv6cwrJoEErr0Mt42FbCMtyz5cV1Kw, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@4d5d61, gatling.http.referer -> https://openam-perf-200m.forgeblocks.com/am/, authcode -> Y_TSoR3_W-hqSCY7YOwDj-EgyHA, callbacks -> {"tokenId":"Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-perf-200m.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1737067108513), CookieKey(15892ffd844ef80,openam-perf-200m.forgeblocks.com,/) -> StoredCookie(15892ffd844ef80=Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1737067108513))), user_uuid -> e906cc76-06c1-41d2-8d74-30ee2880374a, password -> Pa_ssw0rd, challenge -> 5P_AXCMkIeiuCdfDMiVLEC4KXXAX7ip5ZjYxdfv-vV0, gatling.http.cache.baseUrl -> https://openam-perf-200m.forgeblocks.com:443, fake-ip -> 225.113.109.250, verifier -> NjI5MTIxNzIxMzI4NTkyNzIxMQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@40c427fc, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@433a3fd6, tokenId -> Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_2) AppleWebKit/601.3.9 (KHTML, like Gecko) Version/9.0.2 Safari/601.3.9),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007804cc69a2e8@6d3e731d,io.netty.channel.epoll.EpollEventLoop@650eab8) 2025-01-16 22:38:59,509 INFO ========================= 2025-01-16 22:38:59,509 INFO HTTP request: 2025-01-16 22:38:59,509 INFO POST https://openam-perf-200m.forgeblocks.com/openidm/privilege?_action=listPrivileges 2025-01-16 22:38:59,509 INFO headers: 2025-01-16 22:38:59,509 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 2025-01-16 22:38:59,510 INFO referer: https://openam-perf-200m.forgeblocks.com/am/ 2025-01-16 22:38:59,510 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlOTA2Y2M3Ni0wNmMxLTQxZDItOGQ3NC0zMGVlMjg4MDM3NGEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYWZlOTNlNzEtODljYy00OTcxLWEzZmQtNzkzNmQzOWRlYWJhLTM3ODAyMzA5Iiwic3VibmFtZSI6ImU5MDZjYzc2LTA2YzEtNDFkMi04ZDc0LTMwZWUyODgwMzc0YSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtMjAwbS5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiItSWk0b19VX0tnbmplZXBPMDBBYm1wRWNEM00iLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzcwNjcxMDgsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzcwNjcxMDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzM3MDcwNzA4LCJpYXQiOjE3MzcwNjcxMDgsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6ImNSOWR5QUtuTUZUci1yYjFPYXF2aktOOG1SbyJ9.uVWKLNWn0-mfwyv6cwrJoEErr0Mt42FbCMtyz5cV1Kw 2025-01-16 22:38:59,510 INFO Accept-Api-Version: 2025-01-16 22:38:59,510 INFO accept: */* 2025-01-16 22:38:59,510 INFO cookie: amlbcookie=01; 15892ffd844ef80=Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2025-01-16 22:38:59,510 INFO origin: https://openam-perf-200m.forgeblocks.com 2025-01-16 22:38:59,510 INFO host: openam-perf-200m.forgeblocks.com 2025-01-16 22:38:59,510 INFO content-length: 0 2025-01-16 22:38:59,510 INFO cookies: 2025-01-16 22:38:59,510 INFO amlbcookie=01, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2025-01-16 22:38:59,510 INFO 15892ffd844ef80=Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2025-01-16 22:38:59,510 INFO ========================= 2025-01-16 22:38:59,510 INFO HTTP response: 2025-01-16 22:38:59,510 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2025-01-16 22:38:59,510 INFO 22:38:29.056 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 429739: j.i.IOException: Premature close 2025-01-16 22:38:59,510 INFO 22:38:29.056 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2025-01-16 22:38:59,510 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2025-01-16 22:38:59,510 INFO Request: 2025-01-16 22:38:59,510 INFO UI IDM login: KO j.i.IOException: Premature close 2025-01-16 22:38:59,510 INFO ========================= 2025-01-16 22:38:59,510 INFO Session: 2025-01-16 22:38:59,510 INFO Session(idc.benchmark.Mix2024,429739,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlOTA2Y2M3Ni0wNmMxLTQxZDItOGQ3NC0zMGVlMjg4MDM3NGEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZDU2YzQ3YzgtMjA2OC00YzQwLWIxZDktZTkxOTFlNGI4NDNmLTM3NjU1NjMwIiwic3VibmFtZSI6ImU5MDZjYzc2LTA2YzEtNDFkMi04ZDc0LTMwZWUyODgwMzc0YSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtMjAwbS5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJZcmpLenVBd3U0MHg4V2RQZEVOZkduY2Y5YkkiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzcwNjcxMDgsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzcwNjcxMDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzM3MDcwNzA4LCJpYXQiOjE3MzcwNjcxMDgsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6ImlJMzZZSERUb2tHa0lEYWJ2UFQ2WXVNeldySSJ9.1mG6AbUwRX-YPev-fWT4uzdmniigS_faTtpbUYR2Q68, username -> user.177090332, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlOTA2Y2M3Ni0wNmMxLTQxZDItOGQ3NC0zMGVlMjg4MDM3NGEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYWZlOTNlNzEtODljYy00OTcxLWEzZmQtNzkzNmQzOWRlYWJhLTM3ODAyMzA5Iiwic3VibmFtZSI6ImU5MDZjYzc2LTA2YzEtNDFkMi04ZDc0LTMwZWUyODgwMzc0YSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtMjAwbS5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiItSWk0b19VX0tnbmplZXBPMDBBYm1wRWNEM00iLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzcwNjcxMDgsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzcwNjcxMDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzM3MDcwNzA4LCJpYXQiOjE3MzcwNjcxMDgsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6ImNSOWR5QUtuTUZUci1yYjFPYXF2aktOOG1SbyJ9.uVWKLNWn0-mfwyv6cwrJoEErr0Mt42FbCMtyz5cV1Kw, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@4d5d61, gatling.http.referer -> https://openam-perf-200m.forgeblocks.com/am/, authcode -> Y_TSoR3_W-hqSCY7YOwDj-EgyHA, callbacks -> {"tokenId":"Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-perf-200m.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1737067108513), CookieKey(15892ffd844ef80,openam-perf-200m.forgeblocks.com,/) -> StoredCookie(15892ffd844ef80=Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1737067108513))), user_uuid -> e906cc76-06c1-41d2-8d74-30ee2880374a, password -> Pa_ssw0rd, challenge -> 5P_AXCMkIeiuCdfDMiVLEC4KXXAX7ip5ZjYxdfv-vV0, gatling.http.cache.baseUrl -> https://openam-perf-200m.forgeblocks.com:443, fake-ip -> 225.113.109.250, verifier -> NjI5MTIxNzIxMzI4NTkyNzIxMQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@40c427fc, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@433a3fd6, tokenId -> Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_2) AppleWebKit/601.3.9 (KHTML, like Gecko) Version/9.0.2 Safari/601.3.9),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007804cc69a2e8@6d3e731d,io.netty.channel.epoll.EpollEventLoop@650eab8) 2025-01-16 22:38:59,510 INFO ========================= 2025-01-16 22:38:59,510 INFO HTTP request: 2025-01-16 22:38:59,510 INFO POST https://openam-perf-200m.forgeblocks.com/openidm/authentication?_action=login 2025-01-16 22:38:59,510 INFO headers: 2025-01-16 22:38:59,510 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 2025-01-16 22:38:59,510 INFO referer: https://openam-perf-200m.forgeblocks.com/am/ 2025-01-16 22:38:59,510 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlOTA2Y2M3Ni0wNmMxLTQxZDItOGQ3NC0zMGVlMjg4MDM3NGEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYWZlOTNlNzEtODljYy00OTcxLWEzZmQtNzkzNmQzOWRlYWJhLTM3ODAyMzA5Iiwic3VibmFtZSI6ImU5MDZjYzc2LTA2YzEtNDFkMi04ZDc0LTMwZWUyODgwMzc0YSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtMjAwbS5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiItSWk0b19VX0tnbmplZXBPMDBBYm1wRWNEM00iLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzcwNjcxMDgsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzcwNjcxMDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzM3MDcwNzA4LCJpYXQiOjE3MzcwNjcxMDgsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6ImNSOWR5QUtuTUZUci1yYjFPYXF2aktOOG1SbyJ9.uVWKLNWn0-mfwyv6cwrJoEErr0Mt42FbCMtyz5cV1Kw 2025-01-16 22:38:59,510 INFO Accept-Api-Version: 2025-01-16 22:38:59,510 INFO accept: */* 2025-01-16 22:38:59,510 INFO cookie: amlbcookie=01; 15892ffd844ef80=Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2025-01-16 22:38:59,510 INFO origin: https://openam-perf-200m.forgeblocks.com 2025-01-16 22:38:59,510 INFO host: openam-perf-200m.forgeblocks.com 2025-01-16 22:38:59,510 INFO content-length: 0 2025-01-16 22:38:59,510 INFO cookies: 2025-01-16 22:38:59,510 INFO amlbcookie=01, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2025-01-16 22:38:59,510 INFO 15892ffd844ef80=Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2025-01-16 22:38:59,510 INFO ========================= 2025-01-16 22:38:59,510 INFO HTTP response: 2025-01-16 22:38:59,510 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2025-01-16 22:38:59,510 INFO 22:38:29.057 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM features' failed for user 429739: j.i.IOException: Premature close 2025-01-16 22:38:59,510 INFO 22:38:29.057 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2025-01-16 22:38:59,510 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2025-01-16 22:38:59,510 INFO Request: 2025-01-16 22:38:59,510 INFO UI IDM features: KO j.i.IOException: Premature close 2025-01-16 22:38:59,510 INFO ========================= 2025-01-16 22:38:59,510 INFO Session: 2025-01-16 22:38:59,510 INFO Session(idc.benchmark.Mix2024,429739,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlOTA2Y2M3Ni0wNmMxLTQxZDItOGQ3NC0zMGVlMjg4MDM3NGEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZDU2YzQ3YzgtMjA2OC00YzQwLWIxZDktZTkxOTFlNGI4NDNmLTM3NjU1NjMwIiwic3VibmFtZSI6ImU5MDZjYzc2LTA2YzEtNDFkMi04ZDc0LTMwZWUyODgwMzc0YSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtMjAwbS5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJZcmpLenVBd3U0MHg4V2RQZEVOZkduY2Y5YkkiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzcwNjcxMDgsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzcwNjcxMDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzM3MDcwNzA4LCJpYXQiOjE3MzcwNjcxMDgsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6ImlJMzZZSERUb2tHa0lEYWJ2UFQ2WXVNeldySSJ9.1mG6AbUwRX-YPev-fWT4uzdmniigS_faTtpbUYR2Q68, username -> user.177090332, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlOTA2Y2M3Ni0wNmMxLTQxZDItOGQ3NC0zMGVlMjg4MDM3NGEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYWZlOTNlNzEtODljYy00OTcxLWEzZmQtNzkzNmQzOWRlYWJhLTM3ODAyMzA5Iiwic3VibmFtZSI6ImU5MDZjYzc2LTA2YzEtNDFkMi04ZDc0LTMwZWUyODgwMzc0YSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtMjAwbS5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiItSWk0b19VX0tnbmplZXBPMDBBYm1wRWNEM00iLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzcwNjcxMDgsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzcwNjcxMDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzM3MDcwNzA4LCJpYXQiOjE3MzcwNjcxMDgsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6ImNSOWR5QUtuTUZUci1yYjFPYXF2aktOOG1SbyJ9.uVWKLNWn0-mfwyv6cwrJoEErr0Mt42FbCMtyz5cV1Kw, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@4d5d61, gatling.http.referer -> https://openam-perf-200m.forgeblocks.com/am/, authcode -> Y_TSoR3_W-hqSCY7YOwDj-EgyHA, callbacks -> {"tokenId":"Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-perf-200m.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1737067108513), CookieKey(15892ffd844ef80,openam-perf-200m.forgeblocks.com,/) -> StoredCookie(15892ffd844ef80=Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1737067108513))), user_uuid -> e906cc76-06c1-41d2-8d74-30ee2880374a, password -> Pa_ssw0rd, challenge -> 5P_AXCMkIeiuCdfDMiVLEC4KXXAX7ip5ZjYxdfv-vV0, gatling.http.cache.baseUrl -> https://openam-perf-200m.forgeblocks.com:443, fake-ip -> 225.113.109.250, verifier -> NjI5MTIxNzIxMzI4NTkyNzIxMQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@40c427fc, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@433a3fd6, tokenId -> Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_2) AppleWebKit/601.3.9 (KHTML, like Gecko) Version/9.0.2 Safari/601.3.9),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007804cc69a2e8@6d3e731d,io.netty.channel.epoll.EpollEventLoop@650eab8) 2025-01-16 22:38:59,510 INFO ========================= 2025-01-16 22:38:59,510 INFO HTTP request: 2025-01-16 22:38:59,510 INFO GET https://openam-perf-200m.forgeblocks.com/openidm/info/features?_queryFilter=true 2025-01-16 22:38:59,510 INFO headers: 2025-01-16 22:38:59,510 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 2025-01-16 22:38:59,510 INFO referer: https://openam-perf-200m.forgeblocks.com/am/ 2025-01-16 22:38:59,510 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlOTA2Y2M3Ni0wNmMxLTQxZDItOGQ3NC0zMGVlMjg4MDM3NGEiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYWZlOTNlNzEtODljYy00OTcxLWEzZmQtNzkzNmQzOWRlYWJhLTM3ODAyMzA5Iiwic3VibmFtZSI6ImU5MDZjYzc2LTA2YzEtNDFkMi04ZDc0LTMwZWUyODgwMzc0YSIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtMjAwbS5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiItSWk0b19VX0tnbmplZXBPMDBBYm1wRWNEM00iLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzcwNjcxMDgsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzcwNjcxMDgsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzM3MDcwNzA4LCJpYXQiOjE3MzcwNjcxMDgsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6ImNSOWR5QUtuTUZUci1yYjFPYXF2aktOOG1SbyJ9.uVWKLNWn0-mfwyv6cwrJoEErr0Mt42FbCMtyz5cV1Kw 2025-01-16 22:38:59,510 INFO Accept-Api-Version: 2025-01-16 22:38:59,510 INFO accept: */* 2025-01-16 22:38:59,510 INFO cookie: amlbcookie=01; 15892ffd844ef80=Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2025-01-16 22:38:59,510 INFO host: openam-perf-200m.forgeblocks.com 2025-01-16 22:38:59,510 INFO cookies: 2025-01-16 22:38:59,510 INFO amlbcookie=01, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2025-01-16 22:38:59,511 INFO 15892ffd844ef80=Q4lE4gNjT2NA9sGXB9ZG92e20Fo.*AAJTSQACMDIAAlNLABxFSnRFNDNEa1RJVW1mamgrQVFLTkdBMjRUWTQ9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-200m.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2025-01-16 22:38:59,511 INFO ========================= 2025-01-16 22:38:59,511 INFO HTTP response: 2025-01-16 22:38:59,511 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2025-01-16 22:38:59,511 INFO [INFO] ------------------------------------------------------------------------ 2025-01-16 22:38:59,511 INFO [INFO] BUILD SUCCESS 2025-01-16 22:38:59,511 INFO [INFO] ------------------------------------------------------------------------ 2025-01-16 22:38:59,511 INFO [INFO] Total time: 01:00 h 2025-01-16 22:38:59,511 INFO [INFO] Finished at: 2025-01-16T22:38:29Z 2025-01-16 22:38:59,511 INFO [INFO] ------------------------------------------------------------------------ 2025-01-16 22:38:59,511 INFO 2025-01-16 22:38:29,187 INFO Gatling simulation run ended successfully 2025-01-16 22:38:59,511 INFO 2025-01-16 22:38:29,187 DEBUG No archive name provided. No archive will be created 2025-01-16 22:38:59,511 INFO ________________________________________________________________________________ [2025-01-16 22:38:59] benchmark-120 show_stderr : Display stderr order ________________________________________________________________________________ 2025-01-16 22:38:59,511 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/stderr.txt is small (0 lines), printing all lines: 2025-01-16 22:38:59,511 DEBUG ----- output ----- 2025-01-16 22:38:59,511 DEBUG 2025-01-16 22:38:59,511 INFO ________________________________________________________________________________ [2025-01-16 22:38:59] benchmark-120 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 ________________________________________________________________________________ [2025-01-16 22:38:59] benchmark-120 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2025-01-16 22:38:59] benchmark-120 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped