--Task-- name: benchmark-100 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-80'] preceding_task: benchmark-80 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self100-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 100} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-06 23:13:14] benchmark-100 pre : N/A ________________________________________________________________________________ 2024-11-06 23:13:14 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-06 23:13:14 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-100/stdout.txt Pod log : /results/orders/benchmark-100 2024-11-06 23:13:14,015 INFO 2024-11-06 23:13:14,015 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 23:13:14,015 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:14,293 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:14,293 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:14,293 DEBUG --- stdout --- 2024-11-06 23:13:14,293 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:14,293 DEBUG --- stderr --- 2024-11-06 23:13:14,293 DEBUG ________________________________________________________________________________ [2024-11-06 23:13:14] benchmark-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-06 23:13:14,293 INFO 2024-11-06 23:13:14,293 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 23:13:14,293 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:14,570 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:14,570 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:14,570 DEBUG --- stdout --- 2024-11-06 23:13:14,570 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:14,570 DEBUG --- stderr --- 2024-11-06 23:13:14,570 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-100" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self100- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=100 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-06 23:13:14] benchmark-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-06 23:13:14,570 INFO 2024-11-06 23:13:14,570 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 23:13:14,570 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:14,848 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:14,848 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:14,848 DEBUG --- stdout --- 2024-11-06 23:13:14,848 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:14,848 DEBUG --- stderr --- 2024-11-06 23:13:14,848 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/order.json { "name": "benchmark-100", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-100\" --no-reports --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx4G\" --simulation-system-properties=\"-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self100- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=100 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-100" } ________________________________________________________________________________ [2024-11-06 23:13:14] benchmark-100 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-11-06 23:13:14,851 INFO 2024-11-06 23:13:14,851 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-06 23:13:14,851 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-06 23:13:15,071 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-06 23:13:15,071 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:15,071 DEBUG --- stdout --- 2024-11-06 23:13:15,071 DEBUG WAITING 2024-11-06 23:13:15,071 DEBUG --- stderr --- 2024-11-06 23:13:15,071 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-11-06 23:13:15] benchmark-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-06 23:13:15,071 INFO 2024-11-06 23:13:15,071 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 23:13:15,071 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:15,351 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:15,351 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:15,351 DEBUG --- stdout --- 2024-11-06 23:13:15,351 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:15,351 DEBUG --- stderr --- 2024-11-06 23:13:15,351 DEBUG 2024-11-06 23:13:15,352 INFO 2024-11-06 23:13:15,352 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-100/order.json overseer-0-6644bb6697-9gfkx:/results/orders/order.json 2024-11-06 23:13:15,352 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 23:13:16,844 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:16,844 DEBUG --- stdout --- 2024-11-06 23:13:16,844 DEBUG 2024-11-06 23:13:16,844 DEBUG --- stderr --- 2024-11-06 23:13:16,844 DEBUG Order has been successfully forwarded to overseer 2024-11-06 23:13:16 - INFO: Check if Gatling simulation started successfully 2024-11-06 23:13:16,844 INFO 2024-11-06 23:13:16,844 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 23:13:16,844 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 23:13:17,122 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 23:13:17,122 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:17,122 DEBUG --- stdout --- 2024-11-06 23:13:17,122 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 23:13:17,122 DEBUG --- stderr --- 2024-11-06 23:13:17,122 DEBUG 2024-11-06 23:13:17,122 INFO 2024-11-06 23:13:17,122 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx -- head --lines 5000 /results/orders/benchmark-100/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-06 23:13:17,122 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-06 23:13:17,847 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-06 23:13:23,587 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-06 23:13:29,381 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-06 23:13:29,381 INFO [loop_until]: OK (rc = 0) 2024-11-06 23:13:29,381 DEBUG --- stdout --- 2024-11-06 23:13:29,381 DEBUG 2024-11-06 23:13:17,371 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp2w8tolah 2024-11-06 23:13:17,373 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-100" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self100- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=100 -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/tmp2w8tolah/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/tmp2w8tolah/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-06 23:13:29,381 DEBUG --- stderr --- 2024-11-06 23:13:29,381 DEBUG ________________________________________________________________________________ [2024-11-06 23:13:29] benchmark-100 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 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry PASS : Order stopped by itself after 1h and before timeout (1h 12m) ________________________________________________________________________________ [2024-11-07 00:13:39] benchmark-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-07 00:13:39,356 INFO 2024-11-07 00:13:39,356 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-07 00:13:39,356 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:39,656 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:39,656 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:39,656 DEBUG --- stdout --- 2024-11-07 00:13:39,656 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:39,656 DEBUG --- stderr --- 2024-11-07 00:13:39,656 DEBUG 2024-11-07 00:13:39,657 INFO 2024-11-07 00:13:39,657 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx -- rm -f /results/orders/order.json 2024-11-07 00:13:39,657 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-07 00:13:40,431 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:40,431 DEBUG --- stdout --- 2024-11-07 00:13:40,431 DEBUG 2024-11-07 00:13:40,431 DEBUG --- stderr --- 2024-11-07 00:13:40,431 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-07 00:13:40] benchmark-100 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-07 00:13:40 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/order.json 2024-11-07 00:13:40,432 INFO 2024-11-07 00:13:40,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 2024-11-07 00:13:40,432 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:40,707 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:40,707 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:40,707 DEBUG --- stdout --- 2024-11-07 00:13:40,707 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:40,707 DEBUG --- stderr --- 2024-11-07 00:13:40,707 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-07 00:13:40,707 INFO 2024-11-07 00:13:40,707 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-07 00:13:40,707 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:40,984 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:40,984 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:40,984 DEBUG --- stdout --- 2024-11-07 00:13:40,984 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:40,984 DEBUG --- stderr --- 2024-11-07 00:13:40,984 DEBUG 2024-11-07 00:13:40,985 INFO 2024-11-07 00:13:40,985 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-100/rc.txt" ]; then echo "/results/orders/benchmark-100/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-100/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-100/rc.txt file found" 2024-11-07 00:13:40,985 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-07 00:13:41,785 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-07 00:13:41,785 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:41,785 DEBUG --- stdout --- 2024-11-07 00:13:41,785 DEBUG /results/orders/benchmark-100/rc.txt file found 2024-11-07 00:13:41,785 DEBUG --- stderr --- 2024-11-07 00:13:41,785 DEBUG 2024-11-07 00:13:41,785 INFO 2024-11-07 00:13:41,785 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-07 00:13:41,785 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:42,062 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:42,062 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:42,062 DEBUG --- stdout --- 2024-11-07 00:13:42,062 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:42,062 DEBUG --- stderr --- 2024-11-07 00:13:42,062 DEBUG 2024-11-07 00:13:42,062 INFO 2024-11-07 00:13:42,062 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-100/rc.txt 2024-11-07 00:13:42,855 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-07 00:13:42,855 DEBUG --- stdout --- 2024-11-07 00:13:42,855 DEBUG 4 /results/orders/benchmark-100/rc.txt 2024-11-07 00:13:42,855 DEBUG --- stderr --- 2024-11-07 00:13:42,855 DEBUG 2024-11-07 00:13:42,855 INFO - Download file (size 4k) 2024-11-07 00:13:42,855 INFO 2024-11-07 00:13:42,855 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-07 00:13:42,855 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:43,134 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:43,134 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:43,134 DEBUG --- stdout --- 2024-11-07 00:13:43,134 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:43,134 DEBUG --- stderr --- 2024-11-07 00:13:43,134 DEBUG source : /results/orders/benchmark-100/rc.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/rc.txt 2024-11-07 00:13:43,135 INFO 2024-11-07 00:13:43,135 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-07 00:13:43,135 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:43,409 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:43,409 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:43,409 DEBUG --- stdout --- 2024-11-07 00:13:43,409 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:43,409 DEBUG --- stderr --- 2024-11-07 00:13:43,409 DEBUG 2024-11-07 00:13:43,410 INFO 2024-11-07 00:13:43,410 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-100/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/rc.txt 2024-11-07 00:13:43,410 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-07 00:13:44,168 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:44,168 DEBUG --- stdout --- 2024-11-07 00:13:44,168 DEBUG tar: Removing leading `/' from member names 2024-11-07 00:13:44,168 DEBUG --- stderr --- 2024-11-07 00:13:44,168 DEBUG 2024-11-07 00:13:44,168 INFO 2024-11-07 00:13:44,168 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-07 00:13:44,168 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:44,445 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:44,445 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:44,445 DEBUG --- stdout --- 2024-11-07 00:13:44,445 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:44,445 DEBUG --- stderr --- 2024-11-07 00:13:44,445 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-07 00:13:44,446 INFO 2024-11-07 00:13:44,446 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-07 00:13:44,446 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:44,724 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:44,724 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:44,724 DEBUG --- stdout --- 2024-11-07 00:13:44,724 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:44,724 DEBUG --- stderr --- 2024-11-07 00:13:44,724 DEBUG 2024-11-07 00:13:44,725 INFO 2024-11-07 00:13:44,725 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-100/stderr.txt" ]; then echo "/results/orders/benchmark-100/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-100/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-100/stderr.txt file found" 2024-11-07 00:13:44,725 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-07 00:13:45,516 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-07 00:13:45,516 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:45,516 DEBUG --- stdout --- 2024-11-07 00:13:45,516 DEBUG /results/orders/benchmark-100/stderr.txt file found 2024-11-07 00:13:45,516 DEBUG --- stderr --- 2024-11-07 00:13:45,517 DEBUG 2024-11-07 00:13:45,517 INFO 2024-11-07 00:13:45,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-07 00:13:45,517 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:45,794 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:45,794 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:45,794 DEBUG --- stdout --- 2024-11-07 00:13:45,794 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:45,794 DEBUG --- stderr --- 2024-11-07 00:13:45,794 DEBUG 2024-11-07 00:13:45,794 INFO 2024-11-07 00:13:45,795 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-100/stderr.txt 2024-11-07 00:13:46,594 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-07 00:13:46,594 DEBUG --- stdout --- 2024-11-07 00:13:46,594 DEBUG 0 /results/orders/benchmark-100/stderr.txt 2024-11-07 00:13:46,594 DEBUG --- stderr --- 2024-11-07 00:13:46,594 DEBUG 2024-11-07 00:13:46,594 INFO - Download file (size 0k) 2024-11-07 00:13:46,595 INFO 2024-11-07 00:13:46,595 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-07 00:13:46,595 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:46,879 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:46,879 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:46,879 DEBUG --- stdout --- 2024-11-07 00:13:46,879 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:46,879 DEBUG --- stderr --- 2024-11-07 00:13:46,879 DEBUG source : /results/orders/benchmark-100/stderr.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/stderr.txt 2024-11-07 00:13:46,880 INFO 2024-11-07 00:13:46,880 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-07 00:13:46,880 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:47,157 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:47,157 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:47,157 DEBUG --- stdout --- 2024-11-07 00:13:47,157 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:47,157 DEBUG --- stderr --- 2024-11-07 00:13:47,157 DEBUG 2024-11-07 00:13:47,158 INFO 2024-11-07 00:13:47,158 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-100/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/stderr.txt 2024-11-07 00:13:47,158 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-07 00:13:47,912 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:47,912 DEBUG --- stdout --- 2024-11-07 00:13:47,912 DEBUG tar: Removing leading `/' from member names 2024-11-07 00:13:47,912 DEBUG --- stderr --- 2024-11-07 00:13:47,912 DEBUG 2024-11-07 00:13:47,913 INFO 2024-11-07 00:13:47,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-07 00:13:47,913 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:48,195 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:48,195 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:48,195 DEBUG --- stdout --- 2024-11-07 00:13:48,195 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:48,195 DEBUG --- stderr --- 2024-11-07 00:13:48,195 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-07 00:13:48,195 INFO 2024-11-07 00:13:48,195 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-07 00:13:48,195 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:48,471 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:48,472 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:48,472 DEBUG --- stdout --- 2024-11-07 00:13:48,472 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:48,472 DEBUG --- stderr --- 2024-11-07 00:13:48,472 DEBUG 2024-11-07 00:13:48,472 INFO 2024-11-07 00:13:48,472 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-100/stdout.txt" ]; then echo "/results/orders/benchmark-100/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-100/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-100/stdout.txt file found" 2024-11-07 00:13:48,473 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-07 00:13:49,267 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-07 00:13:49,267 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:49,267 DEBUG --- stdout --- 2024-11-07 00:13:49,267 DEBUG /results/orders/benchmark-100/stdout.txt file found 2024-11-07 00:13:49,267 DEBUG --- stderr --- 2024-11-07 00:13:49,267 DEBUG 2024-11-07 00:13:49,267 INFO 2024-11-07 00:13:49,267 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-07 00:13:49,267 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:49,543 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:49,544 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:49,544 DEBUG --- stdout --- 2024-11-07 00:13:49,544 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:49,544 DEBUG --- stderr --- 2024-11-07 00:13:49,544 DEBUG 2024-11-07 00:13:49,544 INFO 2024-11-07 00:13:49,544 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-100/stdout.txt 2024-11-07 00:13:50,336 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-07 00:13:50,336 DEBUG --- stdout --- 2024-11-07 00:13:50,336 DEBUG 4668 /results/orders/benchmark-100/stdout.txt 2024-11-07 00:13:50,336 DEBUG --- stderr --- 2024-11-07 00:13:50,336 DEBUG 2024-11-07 00:13:50,336 INFO - Download file (size 4668k) 2024-11-07 00:13:50,336 INFO 2024-11-07 00:13:50,336 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-07 00:13:50,336 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:50,612 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:50,612 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:50,612 DEBUG --- stdout --- 2024-11-07 00:13:50,612 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:50,612 DEBUG --- stderr --- 2024-11-07 00:13:50,612 DEBUG source : /results/orders/benchmark-100/stdout.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/stdout.txt 2024-11-07 00:13:50,612 INFO 2024-11-07 00:13:50,612 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-07 00:13:50,612 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:50,887 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:50,887 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:50,887 DEBUG --- stdout --- 2024-11-07 00:13:50,887 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:50,887 DEBUG --- stderr --- 2024-11-07 00:13:50,887 DEBUG 2024-11-07 00:13:50,888 INFO 2024-11-07 00:13:50,888 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-100/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/stdout.txt 2024-11-07 00:13:50,888 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-07 00:13:52,140 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:52,140 DEBUG --- stdout --- 2024-11-07 00:13:52,140 DEBUG tar: Removing leading `/' from member names 2024-11-07 00:13:52,140 DEBUG --- stderr --- 2024-11-07 00:13:52,140 DEBUG ________________________________________________________________________________ [2024-11-07 00:13:52] benchmark-100 show_cmd : Display order command ________________________________________________________________________________ 2024-11-07 00:13:52,141 INFO 2024-11-07 00:13:52,141 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-07 00:13:52,141 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:52,417 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:52,417 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:52,417 DEBUG --- stdout --- 2024-11-07 00:13:52,417 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:52,417 DEBUG --- stderr --- 2024-11-07 00:13:52,417 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-07 00:13:52,418 INFO 2024-11-07 00:13:52,418 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-07 00:13:52,418 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:52,694 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:52,694 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:52,694 DEBUG --- stdout --- 2024-11-07 00:13:52,694 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:52,694 DEBUG --- stderr --- 2024-11-07 00:13:52,694 DEBUG 2024-11-07 00:13:52,695 INFO 2024-11-07 00:13:52,695 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-100/cmd.txt" ]; then echo "/results/orders/benchmark-100/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-100/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-100/cmd.txt file found" 2024-11-07 00:13:52,695 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-07 00:13:53,491 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-07 00:13:53,491 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:53,491 DEBUG --- stdout --- 2024-11-07 00:13:53,491 DEBUG /results/orders/benchmark-100/cmd.txt file found 2024-11-07 00:13:53,491 DEBUG --- stderr --- 2024-11-07 00:13:53,491 DEBUG 2024-11-07 00:13:53,491 INFO 2024-11-07 00:13:53,491 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-07 00:13:53,491 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:53,770 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:53,770 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:53,770 DEBUG --- stdout --- 2024-11-07 00:13:53,770 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:53,770 DEBUG --- stderr --- 2024-11-07 00:13:53,770 DEBUG 2024-11-07 00:13:53,770 INFO 2024-11-07 00:13:53,770 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-100/cmd.txt 2024-11-07 00:13:54,563 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-07 00:13:54,563 DEBUG --- stdout --- 2024-11-07 00:13:54,563 DEBUG 4 /results/orders/benchmark-100/cmd.txt 2024-11-07 00:13:54,563 DEBUG --- stderr --- 2024-11-07 00:13:54,563 DEBUG 2024-11-07 00:13:54,563 INFO - Download file (size 4k) 2024-11-07 00:13:54,563 INFO 2024-11-07 00:13:54,563 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-07 00:13:54,563 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:54,841 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:54,841 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:54,841 DEBUG --- stdout --- 2024-11-07 00:13:54,841 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:54,841 DEBUG --- stderr --- 2024-11-07 00:13:54,841 DEBUG source : /results/orders/benchmark-100/cmd.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/cmd.txt 2024-11-07 00:13:54,842 INFO 2024-11-07 00:13:54,842 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-07 00:13:54,842 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-07 00:13:55,117 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-07 00:13:55,117 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:55,117 DEBUG --- stdout --- 2024-11-07 00:13:55,117 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-07 00:13:55,117 DEBUG --- stderr --- 2024-11-07 00:13:55,117 DEBUG 2024-11-07 00:13:55,118 INFO 2024-11-07 00:13:55,118 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-100/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/cmd.txt 2024-11-07 00:13:55,118 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-07 00:13:55,875 INFO [loop_until]: OK (rc = 0) 2024-11-07 00:13:55,875 DEBUG --- stdout --- 2024-11-07 00:13:55,875 DEBUG tar: Removing leading `/' from member names 2024-11-07 00:13:55,875 DEBUG --- stderr --- 2024-11-07 00:13:55,875 DEBUG 2024-11-07 00:13:55,875 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/cmd.txt is small (1 lines), printing all lines: 2024-11-07 00:13:55,875 DEBUG ----- output ----- 2024-11-07 00:13:55,875 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-100" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self100- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=100 -Duser_password=Pa_ssw0rd" 2024-11-07 00:13:55,875 INFO ________________________________________________________________________________ [2024-11-07 00:13:55] benchmark-100 show_rc : Display rc order ________________________________________________________________________________ 2024-11-07 00:13:55,875 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/rc.txt is small (1 lines), printing all lines: 2024-11-07 00:13:55,875 DEBUG ----- output ----- 2024-11-07 00:13:55,875 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-07 00:13:55,875 INFO ________________________________________________________________________________ [2024-11-07 00:13:55] benchmark-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-07 00:13:55,882 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-100/stdout.txt is 58110 lines): 2024-11-07 00:13:55,882 DEBUG ----- output ----- 2024-11-07 00:13:55,882 INFO 2024-11-06 23:13:17,371 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp2w8tolah 2024-11-07 00:13:55,882 INFO 2024-11-06 23:13:17,373 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-100" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self100- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=100 -Duser_password=**** 2024-11-07 00:13:55,882 INFO [INFO] Scanning for projects... 2024-11-07 00:13:55,883 INFO [INFO] 2024-11-07 00:13:55,883 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-07 00:13:55,883 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-07 00:13:55,883 INFO [INFO] from pom.xml 2024-11-07 00:13:55,883 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-07 00:13:55,883 INFO [INFO] 2024-11-07 00:13:55,883 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-07 00:13:55,883 INFO [INFO] 2024-11-07 00:13:55,883 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-07 00:13:55,883 INFO [INFO] skip non existing resourceDirectory /tmp/tmp2w8tolah/src/main/resources 2024-11-07 00:13:55,883 INFO [INFO] 2024-11-07 00:13:55,883 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-07 00:13:55,883 INFO [INFO] No sources to compile 2024-11-07 00:13:55,883 INFO [INFO] 2024-11-07 00:13:55,883 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-07 00:13:55,883 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-07 00:13:55,883 INFO [INFO] 2024-11-07 00:13:55,883 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-07 00:13:55,883 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-07 00:13:55,883 INFO [INFO] 2024-11-07 00:13:55,883 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-07 00:13:55,883 INFO [INFO] compiling 38 Scala sources to /tmp/tmp2w8tolah/target/test-classes ... 2024-11-07 00:13:55,883 INFO [INFO] compile in 6.8 s 2024-11-07 00:13:55,883 INFO [INFO] 2024-11-07 00:13:55,883 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-07 00:13:55,883 INFO [INFO] 2024-11-07 00:13:55,883 INFO [INFO] 2024-11-07 00:13:55,883 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-07 00:13:55,883 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-07 00:13:55,883 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-07 00:13:55,883 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-07 00:13:55,883 INFO 2024-11-07 00:13:55,883 INFO ================================================================================ 2024-11-07 00:13:55,883 INFO 2024-11-06 23:13:33 GMT 5s elapsed 2024-11-07 00:13:55,883 INFO ---- Requests ------------------------------------------------------------------ 2024-11-07 00:13:55,883 INFO > Global (OK=443 KO=0 ) 2024-11-07 00:13:55,883 INFO > restLoginInitiate (OK=40 KO=0 ) 2024-11-07 00:13:55,883 INFO > restLoginUsernamePasswordCallback (OK=38 KO=0 ) 2024-11-07 00:13:55,883 INFO > Skip 2FA (OK=23 KO=0 ) 2024-11-07 00:13:55,883 INFO > User Authorize PKCE stage (OK=44 KO=0 ) 2024-11-07 00:13:55,883 INFO > User AccessToken PKCE stage (OK=43 KO=0 ) 2024-11-07 00:13:55,883 INFO > GetUUid (OK=14 KO=0 ) 2024-11-07 00:13:55,883 INFO > GetOpenidConfig (OK=14 KO=0 ) 2024-11-07 00:13:55,883 INFO > CreatePolicy (OK=14 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI-Post-Login - stage 1 (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI AM idFromSession (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI AM validateGoto (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI-Post-Login - stage 2 (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI Enduser appAuthHelper2 (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > tokenUserinfo (OK=5 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI Enduser appAuthHelper1 (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI-Post-Login - stage 3 (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI Enduser sessioncheck (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI IDM uiconfig (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI IDM version (OK=7 KO=0 ) 2024-11-07 00:13:55,883 INFO > UI IDM privilege (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > UI IDM login (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > UI IDM features (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > UI IDM dashboard (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > UI IDM managed user schema (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > UI IDM user (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > UI IDM oidcToken (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > userinfo (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > UI-Logout (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > UI-Logout Redirect 1 (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > UI AM revoke1 (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > UI AM revoke2 (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > UI AM endsession (OK=7 KO=0 ) 2024-11-07 00:13:55,884 INFO > RootUserinfo (OK=14 KO=0 ) 2024-11-07 00:13:55,884 INFO > getIdToken (OK=2 KO=0 ) 2024-11-07 00:13:55,884 INFO > getIdToken Redirect 1 (OK=2 KO=0 ) 2024-11-07 00:13:55,884 INFO > createOrganization (OK=2 KO=0 ) 2024-11-07 00:13:55,884 INFO > queryOrganization (OK=2 KO=0 ) 2024-11-07 00:13:55,884 INFO > deleteOrganization (OK=2 KO=0 ) 2024-11-07 00:13:55,884 INFO > loginTreeWorkflow_restLoginInitiate (OK=1 KO=0 ) 2024-11-07 00:13:55,884 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-11-07 00:13:55,884 INFO 2024-11-07 00:13:55,884 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-07 00:13:55,884 INFO [- ] 0% 2024-11-07 00:13:55,884 INFO waiting: 360910 / active: 26 / done: 15 2024-11-07 00:13:55,884 INFO ================================================================================ 2024-11-07 00:13:55,884 INFO 2024-11-07 00:13:55,884 INFO 23:13:36.315 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-07 00:13:55,884 INFO 2024-11-07 00:13:55,884 INFO ================================================================================ 2024-11-07 00:13:55,884 INFO 2024-11-06 23:13:38 GMT 10s elapsed 2024-11-07 00:13:55,884 INFO ---- Requests ------------------------------------------------------------------ 2024-11-07 00:13:55,884 INFO > Global (OK=1686 KO=0 ) 2024-11-07 00:13:55,884 INFO > restLoginInitiate (OK=158 KO=0 ) 2024-11-07 00:13:55,884 INFO > restLoginUsernamePasswordCallback (OK=154 KO=0 ) 2024-11-07 00:13:55,884 INFO > Skip 2FA (OK=88 KO=0 ) 2024-11-07 00:13:55,884 INFO > User Authorize PKCE stage (OK=173 KO=0 ) 2024-11-07 00:13:55,884 INFO > User AccessToken PKCE stage (OK=171 KO=0 ) 2024-11-07 00:13:55,884 INFO > GetUUid (OK=48 KO=0 ) 2024-11-07 00:13:55,884 INFO [...] 2024-11-07 00:13:55,896 INFO cookie: amlbcookie=01; 9b75a55744995d0=y5brzCc7Vp9ZnnDvhOkjRl8trXU.*AAJTSQACMDIAAlNLABx4WGtPcWdSdXExV1BEMWxGaFh6ejY0WmZPaEk9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-07 00:13:55,896 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-07 00:13:55,896 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-07 00:13:55,896 INFO content-length: 0 2024-11-07 00:13:55,896 INFO cookies: 2024-11-07 00:13:55,896 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-07 00:13:55,896 INFO 9b75a55744995d0=y5brzCc7Vp9ZnnDvhOkjRl8trXU.*AAJTSQACMDIAAlNLABx4WGtPcWdSdXExV1BEMWxGaFh6ejY0WmZPaEk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-07 00:13:55,896 INFO ========================= 2024-11-07 00:13:55,896 INFO HTTP response: 2024-11-07 00:13:55,896 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-07 00:13:55,896 INFO 00:13:28.224 [ERROR] i.g.c.a.b.SessionHookBuilder$$anon$1 - 'hook-104' crashed with 'j.u.NoSuchElementException: No attribute named 'callbacks' is defined', forwarding to the next one 2024-11-07 00:13:55,896 INFO 00:13:28.224 [ERROR] i.g.c.a.b.SessionHookBuilder$$anon$1 - 'hook-190' crashed with 'j.u.NoSuchElementException: No attribute named 'callbacks' is defined', forwarding to the next one 2024-11-07 00:13:55,896 INFO 00:13:28.224 [ERROR] i.g.c.a.b.SessionHookBuilder$$anon$1 - 'hook-104' crashed with 'j.u.NoSuchElementException: No attribute named 'callbacks' is defined', forwarding to the next one 2024-11-07 00:13:55,896 INFO 00:13:28.224 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM features' failed for user 357829: j.i.IOException: Premature close 2024-11-07 00:13:55,896 INFO 00:13:28.225 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-07 00:13:55,896 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-07 00:13:55,896 INFO Request: 2024-11-07 00:13:55,896 INFO UI IDM features: KO j.i.IOException: Premature close 2024-11-07 00:13:55,896 INFO ========================= 2024-11-07 00:13:55,896 INFO Session: 2024-11-07 00:13:55,896 INFO Session(idc.benchmark.Mix2024,357829,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkODViOTJlYy1hYzJjLTQ4NGEtOGJmMC0zY2NlN2U0MjQ1NzIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMGU4MWU4NmQtODlhYy00YTliLTgwNDEtYzE5ZTVjY2JhOGRmLTU2NTQ1NDIwIiwic3VibmFtZSI6ImQ4NWI5MmVjLWFjMmMtNDg0YS04YmYwLTNjY2U3ZTQyNDU3MiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImRHdmRqam8tM0VDQ2VkTWJSbklkUWJ2bWZnYyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzODQwNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzODQwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5NDIwMDcsImlhdCI6MTczMDkzODQwNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiSVQyV3dSSUNKa29fd0JZVm5nUTdiXzVqTVNzIn0.eDpXKX9ZK9s2UJOs9_igiMISxIrCa14hTNh0NBGHSM8, username -> user.406644, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkODViOTJlYy1hYzJjLTQ4NGEtOGJmMC0zY2NlN2U0MjQ1NzIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMjkyNTgwZDctOWFkMi00MDljLTg4OGItNjYyYmRiNTE2MDQ2LTU3MDYwODgzIiwic3VibmFtZSI6ImQ4NWI5MmVjLWFjMmMtNDg0YS04YmYwLTNjY2U3ZTQyNDU3MiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImZvN1BTZ0ltekhLQzkxVll2djhqWEZWRnpYNCIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzODQwOCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzODQwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5NDIwMDgsImlhdCI6MTczMDkzODQwOCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoicTI5V09QZXdnTUxHUEJfM0Z3M3JGSy1jYzE4In0.IuRTp8YFMOAv_afvjxd7_rU5zg9msfh9lM8IVjFeWGo, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@12a89c0, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> ygLkTkM5fNLoJDAQ2NYMvWrTcc0, callbacks -> {"tokenId":"HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1730938407568), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1730938407568))), user_uuid -> d85b92ec-ac2c-484a-8bf0-3cce7e424572, password -> Pa_ssw0rd, challenge -> T-7Up5hPZH4xts32PHqc6wkarPiChL2H_ylvtRswSWw, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 42.42.42.42, verifier -> LTc0MTg5NTUxODM5MTcxNDEzNzQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7ebe7e9c, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@441af1, tokenId -> HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007f1e1069a0e0@488990da,io.netty.channel.epoll.EpollEventLoop@619bfe29) 2024-11-07 00:13:55,896 INFO ========================= 2024-11-07 00:13:55,896 INFO HTTP request: 2024-11-07 00:13:55,896 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/features?_queryFilter=true 2024-11-07 00:13:55,896 INFO headers: 2024-11-07 00:13:55,896 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-07 00:13:55,896 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-07 00:13:55,896 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkODViOTJlYy1hYzJjLTQ4NGEtOGJmMC0zY2NlN2U0MjQ1NzIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMjkyNTgwZDctOWFkMi00MDljLTg4OGItNjYyYmRiNTE2MDQ2LTU3MDYwODgzIiwic3VibmFtZSI6ImQ4NWI5MmVjLWFjMmMtNDg0YS04YmYwLTNjY2U3ZTQyNDU3MiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImZvN1BTZ0ltekhLQzkxVll2djhqWEZWRnpYNCIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzODQwOCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzODQwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5NDIwMDgsImlhdCI6MTczMDkzODQwOCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoicTI5V09QZXdnTUxHUEJfM0Z3M3JGSy1jYzE4In0.IuRTp8YFMOAv_afvjxd7_rU5zg9msfh9lM8IVjFeWGo 2024-11-07 00:13:55,896 INFO Accept-Api-Version: 2024-11-07 00:13:55,896 INFO accept: */* 2024-11-07 00:13:55,896 INFO cookie: amlbcookie=01; 9b75a55744995d0=HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-07 00:13:55,896 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-07 00:13:55,897 INFO cookies: 2024-11-07 00:13:55,897 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-07 00:13:55,897 INFO 9b75a55744995d0=HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-07 00:13:55,897 INFO ========================= 2024-11-07 00:13:55,897 INFO HTTP response: 2024-11-07 00:13:55,897 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-07 00:13:55,897 INFO 00:13:28.225 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 357829: j.i.IOException: Premature close 2024-11-07 00:13:55,897 INFO 00:13:28.225 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-07 00:13:55,897 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-07 00:13:55,897 INFO Request: 2024-11-07 00:13:55,897 INFO UI IDM login: KO j.i.IOException: Premature close 2024-11-07 00:13:55,897 INFO ========================= 2024-11-07 00:13:55,897 INFO Session: 2024-11-07 00:13:55,897 INFO Session(idc.benchmark.Mix2024,357829,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkODViOTJlYy1hYzJjLTQ4NGEtOGJmMC0zY2NlN2U0MjQ1NzIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMGU4MWU4NmQtODlhYy00YTliLTgwNDEtYzE5ZTVjY2JhOGRmLTU2NTQ1NDIwIiwic3VibmFtZSI6ImQ4NWI5MmVjLWFjMmMtNDg0YS04YmYwLTNjY2U3ZTQyNDU3MiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImRHdmRqam8tM0VDQ2VkTWJSbklkUWJ2bWZnYyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzODQwNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzODQwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5NDIwMDcsImlhdCI6MTczMDkzODQwNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiSVQyV3dSSUNKa29fd0JZVm5nUTdiXzVqTVNzIn0.eDpXKX9ZK9s2UJOs9_igiMISxIrCa14hTNh0NBGHSM8, username -> user.406644, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkODViOTJlYy1hYzJjLTQ4NGEtOGJmMC0zY2NlN2U0MjQ1NzIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMjkyNTgwZDctOWFkMi00MDljLTg4OGItNjYyYmRiNTE2MDQ2LTU3MDYwODgzIiwic3VibmFtZSI6ImQ4NWI5MmVjLWFjMmMtNDg0YS04YmYwLTNjY2U3ZTQyNDU3MiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImZvN1BTZ0ltekhLQzkxVll2djhqWEZWRnpYNCIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzODQwOCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzODQwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5NDIwMDgsImlhdCI6MTczMDkzODQwOCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoicTI5V09QZXdnTUxHUEJfM0Z3M3JGSy1jYzE4In0.IuRTp8YFMOAv_afvjxd7_rU5zg9msfh9lM8IVjFeWGo, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@12a89c0, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> ygLkTkM5fNLoJDAQ2NYMvWrTcc0, callbacks -> {"tokenId":"HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1730938407568), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1730938407568))), user_uuid -> d85b92ec-ac2c-484a-8bf0-3cce7e424572, password -> Pa_ssw0rd, challenge -> T-7Up5hPZH4xts32PHqc6wkarPiChL2H_ylvtRswSWw, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 42.42.42.42, verifier -> LTc0MTg5NTUxODM5MTcxNDEzNzQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7ebe7e9c, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@441af1, tokenId -> HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007f1e1069a0e0@488990da,io.netty.channel.epoll.EpollEventLoop@619bfe29) 2024-11-07 00:13:55,897 INFO ========================= 2024-11-07 00:13:55,897 INFO HTTP request: 2024-11-07 00:13:55,897 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-07 00:13:55,897 INFO headers: 2024-11-07 00:13:55,897 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-07 00:13:55,897 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-07 00:13:55,897 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkODViOTJlYy1hYzJjLTQ4NGEtOGJmMC0zY2NlN2U0MjQ1NzIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMjkyNTgwZDctOWFkMi00MDljLTg4OGItNjYyYmRiNTE2MDQ2LTU3MDYwODgzIiwic3VibmFtZSI6ImQ4NWI5MmVjLWFjMmMtNDg0YS04YmYwLTNjY2U3ZTQyNDU3MiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImZvN1BTZ0ltekhLQzkxVll2djhqWEZWRnpYNCIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzODQwOCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzODQwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5NDIwMDgsImlhdCI6MTczMDkzODQwOCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoicTI5V09QZXdnTUxHUEJfM0Z3M3JGSy1jYzE4In0.IuRTp8YFMOAv_afvjxd7_rU5zg9msfh9lM8IVjFeWGo 2024-11-07 00:13:55,897 INFO Accept-Api-Version: 2024-11-07 00:13:55,897 INFO accept: */* 2024-11-07 00:13:55,897 INFO cookie: amlbcookie=01; 9b75a55744995d0=HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-07 00:13:55,897 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-07 00:13:55,897 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-07 00:13:55,897 INFO content-length: 0 2024-11-07 00:13:55,897 INFO cookies: 2024-11-07 00:13:55,897 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-07 00:13:55,897 INFO 9b75a55744995d0=HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-07 00:13:55,897 INFO ========================= 2024-11-07 00:13:55,897 INFO HTTP response: 2024-11-07 00:13:55,897 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-07 00:13:55,897 INFO 00:13:28.225 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM privilege' failed for user 357829: j.i.IOException: Premature close 2024-11-07 00:13:55,897 INFO 00:13:28.225 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-07 00:13:55,897 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-07 00:13:55,897 INFO Request: 2024-11-07 00:13:55,897 INFO UI IDM privilege: KO j.i.IOException: Premature close 2024-11-07 00:13:55,897 INFO ========================= 2024-11-07 00:13:55,897 INFO Session: 2024-11-07 00:13:55,897 INFO Session(idc.benchmark.Mix2024,357829,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkODViOTJlYy1hYzJjLTQ4NGEtOGJmMC0zY2NlN2U0MjQ1NzIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMGU4MWU4NmQtODlhYy00YTliLTgwNDEtYzE5ZTVjY2JhOGRmLTU2NTQ1NDIwIiwic3VibmFtZSI6ImQ4NWI5MmVjLWFjMmMtNDg0YS04YmYwLTNjY2U3ZTQyNDU3MiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImRHdmRqam8tM0VDQ2VkTWJSbklkUWJ2bWZnYyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzODQwNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzODQwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5NDIwMDcsImlhdCI6MTczMDkzODQwNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiSVQyV3dSSUNKa29fd0JZVm5nUTdiXzVqTVNzIn0.eDpXKX9ZK9s2UJOs9_igiMISxIrCa14hTNh0NBGHSM8, username -> user.406644, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkODViOTJlYy1hYzJjLTQ4NGEtOGJmMC0zY2NlN2U0MjQ1NzIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMjkyNTgwZDctOWFkMi00MDljLTg4OGItNjYyYmRiNTE2MDQ2LTU3MDYwODgzIiwic3VibmFtZSI6ImQ4NWI5MmVjLWFjMmMtNDg0YS04YmYwLTNjY2U3ZTQyNDU3MiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImZvN1BTZ0ltekhLQzkxVll2djhqWEZWRnpYNCIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzODQwOCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzODQwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5NDIwMDgsImlhdCI6MTczMDkzODQwOCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoicTI5V09QZXdnTUxHUEJfM0Z3M3JGSy1jYzE4In0.IuRTp8YFMOAv_afvjxd7_rU5zg9msfh9lM8IVjFeWGo, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@12a89c0, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> ygLkTkM5fNLoJDAQ2NYMvWrTcc0, callbacks -> {"tokenId":"HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1730938407568), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1730938407568))), user_uuid -> d85b92ec-ac2c-484a-8bf0-3cce7e424572, password -> Pa_ssw0rd, challenge -> T-7Up5hPZH4xts32PHqc6wkarPiChL2H_ylvtRswSWw, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 42.42.42.42, verifier -> LTc0MTg5NTUxODM5MTcxNDEzNzQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7ebe7e9c, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@441af1, tokenId -> HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007f1e1069a0e0@488990da,io.netty.channel.epoll.EpollEventLoop@619bfe29) 2024-11-07 00:13:55,897 INFO ========================= 2024-11-07 00:13:55,897 INFO HTTP request: 2024-11-07 00:13:55,897 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/privilege?_action=listPrivileges 2024-11-07 00:13:55,897 INFO headers: 2024-11-07 00:13:55,897 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-07 00:13:55,897 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-07 00:13:55,897 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkODViOTJlYy1hYzJjLTQ4NGEtOGJmMC0zY2NlN2U0MjQ1NzIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMjkyNTgwZDctOWFkMi00MDljLTg4OGItNjYyYmRiNTE2MDQ2LTU3MDYwODgzIiwic3VibmFtZSI6ImQ4NWI5MmVjLWFjMmMtNDg0YS04YmYwLTNjY2U3ZTQyNDU3MiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImZvN1BTZ0ltekhLQzkxVll2djhqWEZWRnpYNCIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMDkzODQwOCwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMDkzODQwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzA5NDIwMDgsImlhdCI6MTczMDkzODQwOCwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoicTI5V09QZXdnTUxHUEJfM0Z3M3JGSy1jYzE4In0.IuRTp8YFMOAv_afvjxd7_rU5zg9msfh9lM8IVjFeWGo 2024-11-07 00:13:55,897 INFO Accept-Api-Version: 2024-11-07 00:13:55,897 INFO accept: */* 2024-11-07 00:13:55,897 INFO cookie: amlbcookie=01; 9b75a55744995d0=HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-07 00:13:55,897 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-07 00:13:55,897 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-07 00:13:55,897 INFO content-length: 0 2024-11-07 00:13:55,897 INFO cookies: 2024-11-07 00:13:55,897 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-07 00:13:55,897 INFO 9b75a55744995d0=HM0nbvf0is1q7zcXh9-9Y8N2fRY.*AAJTSQACMDIAAlNLABw4QnNSQUQ5OUk3Wm0xenF4aG9OZjcyaWZvdms9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-07 00:13:55,897 INFO ========================= 2024-11-07 00:13:55,898 INFO HTTP response: 2024-11-07 00:13:55,898 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-07 00:13:55,898 INFO [INFO] ------------------------------------------------------------------------ 2024-11-07 00:13:55,898 INFO [INFO] BUILD SUCCESS 2024-11-07 00:13:55,898 INFO [INFO] ------------------------------------------------------------------------ 2024-11-07 00:13:55,898 INFO [INFO] Total time: 01:00 h 2024-11-07 00:13:55,898 INFO [INFO] Finished at: 2024-11-07T00:13:28Z 2024-11-07 00:13:55,898 INFO [INFO] ------------------------------------------------------------------------ 2024-11-07 00:13:55,898 INFO 2024-11-07 00:13:28,345 INFO Gatling simulation run ended successfully 2024-11-07 00:13:55,898 INFO 2024-11-07 00:13:28,345 DEBUG No archive name provided. No archive will be created 2024-11-07 00:13:55,898 INFO ________________________________________________________________________________ [2024-11-07 00:13:55] benchmark-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-07 00:13:55,898 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-100/stderr.txt is small (0 lines), printing all lines: 2024-11-07 00:13:55,898 DEBUG ----- output ----- 2024-11-07 00:13:55,898 DEBUG 2024-11-07 00:13:55,898 INFO ________________________________________________________________________________ [2024-11-07 00:13:55] benchmark-100 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-07 00:13:55] benchmark-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-07 00:13:55] benchmark-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped