--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-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=self120-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 120} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-18 19:19:36] benchmark-120 pre : N/A ________________________________________________________________________________ 2024-11-18 19:19:36 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-18 19:19:36 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-120/stdout.txt Pod log : /results/orders/benchmark-120 2024-11-18 19:19:36,371 INFO 2024-11-18 19:19:36,371 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-18 19:19:36,371 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:36,643 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:36,643 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:36,643 DEBUG --- stdout --- 2024-11-18 19:19:36,643 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:36,643 DEBUG --- stderr --- 2024-11-18 19:19:36,643 DEBUG ________________________________________________________________________________ [2024-11-18 19:19:36] benchmark-120 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-18 19:19:36,643 INFO 2024-11-18 19:19:36,643 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-18 19:19:36,643 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:36,913 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:36,913 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:36,913 DEBUG --- stdout --- 2024-11-18 19:19:36,913 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:36,913 DEBUG --- stderr --- 2024-11-18 19:19:36,913 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-120" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=200000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self120- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=120 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-18 19:19:36] benchmark-120 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-18 19:19:36,914 INFO 2024-11-18 19:19:36,914 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-18 19:19:36,914 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:37,184 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:37,184 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:37,184 DEBUG --- stdout --- 2024-11-18 19:19:37,184 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:37,184 DEBUG --- stderr --- 2024-11-18 19:19:37,184 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 run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-120\" --no-reports --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx4G\" --simulation-system-properties=\"-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=200000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self120- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=120 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-120" } ________________________________________________________________________________ [2024-11-18 19:19:37] 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: ________________________________________________________________________________ 2024-11-18 19:19:37,187 INFO 2024-11-18 19:19:37,187 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-18 19:19:37,187 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 19:19:37,401 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 19:19:37,401 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:37,401 DEBUG --- stdout --- 2024-11-18 19:19:37,401 DEBUG WAITING 2024-11-18 19:19:37,401 DEBUG --- stderr --- 2024-11-18 19:19:37,401 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 34 0 --:--:-- --:--:-- --:--:-- 34 ________________________________________________________________________________ [2024-11-18 19:19:37] benchmark-120 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-18 19:19:37,401 INFO 2024-11-18 19:19:37,401 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-18 19:19:37,401 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:37,673 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:37,673 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:37,673 DEBUG --- stdout --- 2024-11-18 19:19:37,674 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:37,674 DEBUG --- stderr --- 2024-11-18 19:19:37,674 DEBUG 2024-11-18 19:19:37,674 INFO 2024-11-18 19:19:37,674 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-6644bb6697-rchxr:/results/orders/order.json 2024-11-18 19:19:37,674 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 19:19:39,042 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:39,042 DEBUG --- stdout --- 2024-11-18 19:19:39,042 DEBUG 2024-11-18 19:19:39,042 DEBUG --- stderr --- 2024-11-18 19:19:39,042 DEBUG Order has been successfully forwarded to overseer 2024-11-18 19:19:39 - INFO: Check if Gatling simulation started successfully 2024-11-18 19:19:39,042 INFO 2024-11-18 19:19:39,042 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-18 19:19:39,042 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 19:19:39,320 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 19:19:39,320 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:39,320 DEBUG --- stdout --- 2024-11-18 19:19:39,320 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 19:19:39,320 DEBUG --- stderr --- 2024-11-18 19:19:39,320 DEBUG 2024-11-18 19:19:39,320 INFO 2024-11-18 19:19:39,320 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr -- head --lines 5000 /results/orders/benchmark-120/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-18 19:19:39,320 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 19:19:40,039 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 19:19:45,759 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 19:19:51,474 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-18 19:19:51,474 INFO [loop_until]: OK (rc = 0) 2024-11-18 19:19:51,475 DEBUG --- stdout --- 2024-11-18 19:19:51,475 DEBUG 2024-11-18 19:19:39,853 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpk9j9wysm 2024-11-18 19:19:39,855 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-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=200000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self120- -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/tmpk9j9wysm/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/tmpk9j9wysm/target/test-classes ... [INFO] compile in 7.2 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... 2024-11-18 19:19:51,475 DEBUG --- stderr --- 2024-11-18 19:19:51,475 DEBUG ________________________________________________________________________________ [2024-11-18 19:19:51] 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 01s, 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 03s, retry Order is running since 30m 04s, retry Order is running since 32m 04s, retry Order is running since 34m 04s, retry Order is running since 36m 05s, retry Order is running since 38m 05s, retry Order is running since 40m 05s, retry Order is running since 42m 05s, retry Order is running since 44m 06s, retry Order is running since 46m 06s, retry Order is running since 48m 06s, retry Order is running since 50m 07s, retry Order is running since 52m 07s, retry Order is running since 54m 07s, retry Order is running since 56m 08s, retry Order is running since 58m 08s, retry PASS : Order stopped by itself after 1h and before timeout (1h 12m) ________________________________________________________________________________ [2024-11-18 20:20:00] benchmark-120 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-18 20:20:00,112 INFO 2024-11-18 20:20:00,112 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-18 20:20:00,112 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:00,407 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:00,407 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:00,408 DEBUG --- stdout --- 2024-11-18 20:20:00,409 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:00,409 DEBUG --- stderr --- 2024-11-18 20:20:00,409 DEBUG 2024-11-18 20:20:00,409 INFO 2024-11-18 20:20:00,409 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr -- rm -f /results/orders/order.json 2024-11-18 20:20:00,409 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 20:20:01,174 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:01,174 DEBUG --- stdout --- 2024-11-18 20:20:01,174 DEBUG 2024-11-18 20:20:01,174 DEBUG --- stderr --- 2024-11-18 20:20:01,174 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-18 20:20:01] benchmark-120 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-18 20:20:01 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/order.json 2024-11-18 20:20:01,174 INFO 2024-11-18 20:20:01,174 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-18 20:20:01,174 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:01,446 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:01,446 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:01,446 DEBUG --- stdout --- 2024-11-18 20:20:01,446 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:01,446 DEBUG --- stderr --- 2024-11-18 20:20:01,446 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 20:20:01,446 INFO 2024-11-18 20:20:01,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-18 20:20:01,446 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:01,718 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:01,718 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:01,718 DEBUG --- stdout --- 2024-11-18 20:20:01,718 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:01,718 DEBUG --- stderr --- 2024-11-18 20:20:01,718 DEBUG 2024-11-18 20:20:01,719 INFO 2024-11-18 20:20:01,719 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- bash -c 'if [ -f "/results/orders/benchmark-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" 2024-11-18 20:20:01,719 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 20:20:02,501 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 20:20:02,501 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:02,501 DEBUG --- stdout --- 2024-11-18 20:20:02,501 DEBUG /results/orders/benchmark-120/rc.txt file found 2024-11-18 20:20:02,501 DEBUG --- stderr --- 2024-11-18 20:20:02,501 DEBUG 2024-11-18 20:20:02,501 INFO 2024-11-18 20:20:02,501 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-18 20:20:02,501 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:02,773 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:02,773 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:02,773 DEBUG --- stdout --- 2024-11-18 20:20:02,773 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:02,774 DEBUG --- stderr --- 2024-11-18 20:20:02,774 DEBUG 2024-11-18 20:20:02,774 INFO 2024-11-18 20:20:02,774 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- du -k /results/orders/benchmark-120/rc.txt 2024-11-18 20:20:03,553 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 20:20:03,553 DEBUG --- stdout --- 2024-11-18 20:20:03,553 DEBUG 4 /results/orders/benchmark-120/rc.txt 2024-11-18 20:20:03,553 DEBUG --- stderr --- 2024-11-18 20:20:03,553 DEBUG 2024-11-18 20:20:03,553 INFO - Download file (size 4k) 2024-11-18 20:20:03,553 INFO 2024-11-18 20:20:03,553 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-18 20:20:03,553 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:03,824 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:03,824 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:03,824 DEBUG --- stdout --- 2024-11-18 20:20:03,824 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:03,824 DEBUG --- stderr --- 2024-11-18 20:20:03,824 DEBUG source : /results/orders/benchmark-120/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/rc.txt 2024-11-18 20:20:03,824 INFO 2024-11-18 20:20:03,824 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-18 20:20:03,824 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:04,100 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:04,100 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:04,100 DEBUG --- stdout --- 2024-11-18 20:20:04,100 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:04,100 DEBUG --- stderr --- 2024-11-18 20:20:04,100 DEBUG 2024-11-18 20:20:04,100 INFO 2024-11-18 20:20:04,100 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-120/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/rc.txt 2024-11-18 20:20:04,100 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 20:20:04,844 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:04,844 DEBUG --- stdout --- 2024-11-18 20:20:04,844 DEBUG tar: Removing leading `/' from member names 2024-11-18 20:20:04,844 DEBUG --- stderr --- 2024-11-18 20:20:04,844 DEBUG 2024-11-18 20:20:04,844 INFO 2024-11-18 20:20:04,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-18 20:20:04,844 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:05,133 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:05,133 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:05,133 DEBUG --- stdout --- 2024-11-18 20:20:05,133 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:05,133 DEBUG --- stderr --- 2024-11-18 20:20:05,133 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 20:20:05,133 INFO 2024-11-18 20:20:05,133 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-18 20:20:05,133 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:05,409 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:05,409 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:05,409 DEBUG --- stdout --- 2024-11-18 20:20:05,409 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:05,409 DEBUG --- stderr --- 2024-11-18 20:20:05,409 DEBUG 2024-11-18 20:20:05,410 INFO 2024-11-18 20:20:05,410 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- bash -c 'if [ -f "/results/orders/benchmark-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" 2024-11-18 20:20:05,410 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 20:20:06,190 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 20:20:06,190 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:06,190 DEBUG --- stdout --- 2024-11-18 20:20:06,190 DEBUG /results/orders/benchmark-120/stderr.txt file found 2024-11-18 20:20:06,190 DEBUG --- stderr --- 2024-11-18 20:20:06,190 DEBUG 2024-11-18 20:20:06,190 INFO 2024-11-18 20:20:06,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 2024-11-18 20:20:06,190 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:06,462 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:06,462 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:06,463 DEBUG --- stdout --- 2024-11-18 20:20:06,463 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:06,463 DEBUG --- stderr --- 2024-11-18 20:20:06,463 DEBUG 2024-11-18 20:20:06,463 INFO 2024-11-18 20:20:06,463 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- du -k /results/orders/benchmark-120/stderr.txt 2024-11-18 20:20:07,243 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 20:20:07,243 DEBUG --- stdout --- 2024-11-18 20:20:07,243 DEBUG 0 /results/orders/benchmark-120/stderr.txt 2024-11-18 20:20:07,243 DEBUG --- stderr --- 2024-11-18 20:20:07,243 DEBUG 2024-11-18 20:20:07,243 INFO - Download file (size 0k) 2024-11-18 20:20:07,244 INFO 2024-11-18 20:20:07,244 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-18 20:20:07,244 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:07,518 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:07,519 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:07,519 DEBUG --- stdout --- 2024-11-18 20:20:07,519 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:07,519 DEBUG --- stderr --- 2024-11-18 20:20:07,519 DEBUG source : /results/orders/benchmark-120/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/stderr.txt 2024-11-18 20:20:07,519 INFO 2024-11-18 20:20:07,519 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-18 20:20:07,519 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:07,791 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:07,791 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:07,791 DEBUG --- stdout --- 2024-11-18 20:20:07,791 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:07,791 DEBUG --- stderr --- 2024-11-18 20:20:07,791 DEBUG 2024-11-18 20:20:07,792 INFO 2024-11-18 20:20:07,792 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-120/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/stderr.txt 2024-11-18 20:20:07,792 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 20:20:08,496 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:08,496 DEBUG --- stdout --- 2024-11-18 20:20:08,496 DEBUG tar: Removing leading `/' from member names 2024-11-18 20:20:08,496 DEBUG --- stderr --- 2024-11-18 20:20:08,496 DEBUG 2024-11-18 20:20:08,497 INFO 2024-11-18 20:20:08,497 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-18 20:20:08,497 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:08,781 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:08,782 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:08,782 DEBUG --- stdout --- 2024-11-18 20:20:08,782 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:08,782 DEBUG --- stderr --- 2024-11-18 20:20:08,782 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 20:20:08,782 INFO 2024-11-18 20:20:08,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 2024-11-18 20:20:08,782 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:09,054 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:09,054 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:09,054 DEBUG --- stdout --- 2024-11-18 20:20:09,054 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:09,054 DEBUG --- stderr --- 2024-11-18 20:20:09,054 DEBUG 2024-11-18 20:20:09,055 INFO 2024-11-18 20:20:09,055 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- bash -c 'if [ -f "/results/orders/benchmark-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" 2024-11-18 20:20:09,055 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 20:20:09,843 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 20:20:09,843 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:09,843 DEBUG --- stdout --- 2024-11-18 20:20:09,843 DEBUG /results/orders/benchmark-120/stdout.txt file found 2024-11-18 20:20:09,843 DEBUG --- stderr --- 2024-11-18 20:20:09,844 DEBUG 2024-11-18 20:20:09,844 INFO 2024-11-18 20:20:09,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-18 20:20:09,844 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:10,130 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:10,130 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:10,130 DEBUG --- stdout --- 2024-11-18 20:20:10,130 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:10,130 DEBUG --- stderr --- 2024-11-18 20:20:10,130 DEBUG 2024-11-18 20:20:10,130 INFO 2024-11-18 20:20:10,130 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- du -k /results/orders/benchmark-120/stdout.txt 2024-11-18 20:20:10,922 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 20:20:10,922 DEBUG --- stdout --- 2024-11-18 20:20:10,922 DEBUG 7840 /results/orders/benchmark-120/stdout.txt 2024-11-18 20:20:10,922 DEBUG --- stderr --- 2024-11-18 20:20:10,922 DEBUG 2024-11-18 20:20:10,922 INFO - Download file (size 7840k) 2024-11-18 20:20:10,922 INFO 2024-11-18 20:20:10,922 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-18 20:20:10,922 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:11,197 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:11,197 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:11,197 DEBUG --- stdout --- 2024-11-18 20:20:11,197 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:11,197 DEBUG --- stderr --- 2024-11-18 20:20:11,197 DEBUG source : /results/orders/benchmark-120/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/stdout.txt 2024-11-18 20:20:11,197 INFO 2024-11-18 20:20:11,198 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-18 20:20:11,198 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:11,470 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:11,471 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:11,471 DEBUG --- stdout --- 2024-11-18 20:20:11,471 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:11,471 DEBUG --- stderr --- 2024-11-18 20:20:11,471 DEBUG 2024-11-18 20:20:11,472 INFO 2024-11-18 20:20:11,472 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-120/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/stdout.txt 2024-11-18 20:20:11,472 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 20:20:12,806 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:12,806 DEBUG --- stdout --- 2024-11-18 20:20:12,806 DEBUG tar: Removing leading `/' from member names 2024-11-18 20:20:12,806 DEBUG --- stderr --- 2024-11-18 20:20:12,806 DEBUG ________________________________________________________________________________ [2024-11-18 20:20:12] benchmark-120 show_cmd : Display order command ________________________________________________________________________________ 2024-11-18 20:20:12,807 INFO 2024-11-18 20:20:12,807 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-18 20:20:12,807 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:13,085 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:13,085 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:13,085 DEBUG --- stdout --- 2024-11-18 20:20:13,085 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:13,086 DEBUG --- stderr --- 2024-11-18 20:20:13,086 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 20:20:13,086 INFO 2024-11-18 20:20:13,086 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-18 20:20:13,086 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:13,359 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:13,359 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:13,359 DEBUG --- stdout --- 2024-11-18 20:20:13,359 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:13,359 DEBUG --- stderr --- 2024-11-18 20:20:13,359 DEBUG 2024-11-18 20:20:13,360 INFO 2024-11-18 20:20:13,360 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- bash -c 'if [ -f "/results/orders/benchmark-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" 2024-11-18 20:20:13,360 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 20:20:14,151 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 20:20:14,151 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:14,151 DEBUG --- stdout --- 2024-11-18 20:20:14,151 DEBUG /results/orders/benchmark-120/cmd.txt file found 2024-11-18 20:20:14,151 DEBUG --- stderr --- 2024-11-18 20:20:14,151 DEBUG 2024-11-18 20:20:14,151 INFO 2024-11-18 20:20:14,151 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-18 20:20:14,151 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:14,423 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:14,423 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:14,423 DEBUG --- stdout --- 2024-11-18 20:20:14,423 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:14,423 DEBUG --- stderr --- 2024-11-18 20:20:14,423 DEBUG 2024-11-18 20:20:14,423 INFO 2024-11-18 20:20:14,423 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr --stdin -- du -k /results/orders/benchmark-120/cmd.txt 2024-11-18 20:20:15,205 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 20:20:15,205 DEBUG --- stdout --- 2024-11-18 20:20:15,205 DEBUG 4 /results/orders/benchmark-120/cmd.txt 2024-11-18 20:20:15,205 DEBUG --- stderr --- 2024-11-18 20:20:15,205 DEBUG 2024-11-18 20:20:15,205 INFO - Download file (size 4k) 2024-11-18 20:20:15,205 INFO 2024-11-18 20:20:15,205 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-18 20:20:15,205 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:15,479 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:15,479 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:15,479 DEBUG --- stdout --- 2024-11-18 20:20:15,479 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:15,479 DEBUG --- stderr --- 2024-11-18 20:20:15,479 DEBUG source : /results/orders/benchmark-120/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/cmd.txt 2024-11-18 20:20:15,479 INFO 2024-11-18 20:20:15,479 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 20:20:15,479 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 20:20:15,750 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 20:20:15,750 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:15,750 DEBUG --- stdout --- 2024-11-18 20:20:15,750 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 20:20:15,750 DEBUG --- stderr --- 2024-11-18 20:20:15,750 DEBUG 2024-11-18 20:20:15,750 INFO 2024-11-18 20:20:15,750 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-120/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/04_benchmark-120/cmd.txt 2024-11-18 20:20:15,750 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 20:20:16,494 INFO [loop_until]: OK (rc = 0) 2024-11-18 20:20:16,494 DEBUG --- stdout --- 2024-11-18 20:20:16,494 DEBUG tar: Removing leading `/' from member names 2024-11-18 20:20:16,494 DEBUG --- stderr --- 2024-11-18 20:20:16,494 DEBUG 2024-11-18 20:20:16,494 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: 2024-11-18 20:20:16,494 DEBUG ----- output ----- 2024-11-18 20:20:16,494 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-120" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=200000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self120- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=120 -Duser_password=Pa_ssw0rd" 2024-11-18 20:20:16,494 INFO ________________________________________________________________________________ [2024-11-18 20:20:16] benchmark-120 show_rc : Display rc order ________________________________________________________________________________ 2024-11-18 20:20:16,494 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: 2024-11-18 20:20:16,494 DEBUG ----- output ----- 2024-11-18 20:20:16,494 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-18 20:20:16,494 INFO ________________________________________________________________________________ [2024-11-18 20:20:16] benchmark-120 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-18 20:20:16,504 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 84246 lines): 2024-11-18 20:20:16,504 DEBUG ----- output ----- 2024-11-18 20:20:16,504 INFO 2024-11-18 19:19:39,853 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpk9j9wysm 2024-11-18 20:20:16,504 INFO 2024-11-18 19:19:39,855 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-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=200000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self120- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=120 -Duser_password=**** 2024-11-18 20:20:16,504 INFO [INFO] Scanning for projects... 2024-11-18 20:20:16,504 INFO [INFO] 2024-11-18 20:20:16,504 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-18 20:20:16,504 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-18 20:20:16,504 INFO [INFO] from pom.xml 2024-11-18 20:20:16,504 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-18 20:20:16,504 INFO [INFO] 2024-11-18 20:20:16,504 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-18 20:20:16,504 INFO [INFO] 2024-11-18 20:20:16,505 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-18 20:20:16,505 INFO [INFO] skip non existing resourceDirectory /tmp/tmpk9j9wysm/src/main/resources 2024-11-18 20:20:16,505 INFO [INFO] 2024-11-18 20:20:16,505 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-18 20:20:16,505 INFO [INFO] No sources to compile 2024-11-18 20:20:16,505 INFO [INFO] 2024-11-18 20:20:16,505 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-18 20:20:16,505 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-18 20:20:16,505 INFO [INFO] 2024-11-18 20:20:16,505 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-18 20:20:16,505 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-18 20:20:16,505 INFO [INFO] 2024-11-18 20:20:16,505 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-18 20:20:16,505 INFO [INFO] compiling 38 Scala sources to /tmp/tmpk9j9wysm/target/test-classes ... 2024-11-18 20:20:16,505 INFO [INFO] compile in 7.2 s 2024-11-18 20:20:16,505 INFO [INFO] 2024-11-18 20:20:16,505 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-18 20:20:16,505 INFO [INFO] 2024-11-18 20:20:16,505 INFO [INFO] 2024-11-18 20:20:16,505 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-18 20:20:16,505 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-18 20:20:16,505 INFO Gatling 3.13.1 is available! (you're using 3.12.0) 2024-11-18 20:20:16,505 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-18 20:20:16,505 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-18 20:20:16,505 INFO 19:19:52.257 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 20:20:16,505 INFO 19:19:52.751 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 20:20:16,505 INFO 2024-11-18 20:20:16,505 INFO ================================================================================ 2024-11-18 20:20:16,505 INFO 2024-11-18 19:19:56 GMT 5s elapsed 2024-11-18 20:20:16,505 INFO ---- Requests ------------------------------------------------------------------ 2024-11-18 20:20:16,505 INFO > Global (OK=515 KO=0 ) 2024-11-18 20:20:16,505 INFO > restLoginInitiate (OK=47 KO=0 ) 2024-11-18 20:20:16,505 INFO > restLoginUsernamePasswordCallback (OK=45 KO=0 ) 2024-11-18 20:20:16,505 INFO > User Authorize PKCE stage (OK=49 KO=0 ) 2024-11-18 20:20:16,505 INFO > User AccessToken PKCE stage (OK=47 KO=0 ) 2024-11-18 20:20:16,505 INFO > tokenUserinfo (OK=9 KO=0 ) 2024-11-18 20:20:16,505 INFO > restloginProgressiveProfileCallback (OK=4 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI-Post-Login - stage 1 (OK=13 KO=0 ) 2024-11-18 20:20:16,505 INFO > Skip 2FA (OK=20 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=12 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI AM idFromSession (OK=12 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI AM validateGoto (OK=12 KO=0 ) 2024-11-18 20:20:16,505 INFO > GetUUid (OK=11 KO=0 ) 2024-11-18 20:20:16,505 INFO > GetOpenidConfig (OK=11 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI-Post-Login - stage 2 (OK=11 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=10 KO=0 ) 2024-11-18 20:20:16,505 INFO > CreatePolicy (OK=11 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI Enduser appAuthHelper2 (OK=10 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI Enduser appAuthHelper1 (OK=10 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI-Post-Login - stage 3 (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI IDM uiconfig (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI Enduser sessioncheck (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI IDM version (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI IDM features (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI IDM privilege (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI IDM login (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI IDM dashboard (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI IDM managed user schema (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI IDM user (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI IDM oidcToken (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > userinfo (OK=8 KO=0 ) 2024-11-18 20:20:16,505 INFO > UI-Logout (OK=8 KO=0 ) 2024-11-18 20:20:16,506 INFO > UI-Logout Redirect 1 (OK=8 KO=0 ) 2024-11-18 20:20:16,506 INFO > UI AM endsession (OK=8 KO=0 ) 2024-11-18 20:20:16,506 INFO > UI AM revoke1 (OK=8 KO=0 ) 2024-11-18 20:20:16,506 INFO > UI AM revoke2 (OK=8 KO=0 ) 2024-11-18 20:20:16,506 INFO > getIdToken (OK=4 KO=0 ) 2024-11-18 20:20:16,506 INFO > getIdToken Redirect 1 (OK=4 KO=0 ) 2024-11-18 20:20:16,506 INFO > createOrganization (OK=4 KO=0 ) 2024-11-18 20:20:16,506 INFO > queryOrganization (OK=4 KO=0 ) 2024-11-18 20:20:16,506 INFO > deleteOrganization (OK=4 KO=0 ) 2024-11-18 20:20:16,506 INFO > RootUserinfo (OK=3 KO=0 ) 2024-11-18 20:20:16,506 INFO > loginTreeWorkflow_restLoginInitiate (OK=2 KO=0 ) 2024-11-18 20:20:16,506 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=2 KO=0 ) 2024-11-18 20:20:16,506 INFO ---- Errors -------------------------------------------------------------------- 2024-11-18 20:20:16,506 INFO > tokenUserinfo: No attribute named 'access_token' is defined 2 (100.0%) 2024-11-18 20:20:16,506 INFO 2024-11-18 20:20:16,506 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-18 20:20:16,506 INFO [- ] 0% 2024-11-18 20:20:16,506 INFO waiting: 433797 / active: 25 / done: 25 2024-11-18 20:20:16,506 INFO ================================================================================ 2024-11-18 20:20:16,506 INFO 2024-11-18 20:20:16,506 INFO 19:19:57.148 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 20:20:16,506 INFO 19:19:58.501 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 20:20:16,506 INFO 19:19:59.060 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 20:20:16,506 INFO 19:20:00.381 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 20:20:16,506 INFO 19:20:00.475 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 20:20:16,506 INFO 19:20:00.549 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 20:20:16,506 INFO [...] 2024-11-18 20:20:16,520 INFO ========================= 2024-11-18 20:20:16,521 INFO HTTP request: 2024-11-18 20:20:16,521 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-18 20:20:16,521 INFO headers: 2024-11-18 20:20:16,521 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-18 20:20:16,521 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 20:20:16,521 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTFhYzUxYy1kOWE0LTQxMTUtYTk5NC00YTU3YTc0MzBlNTIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZWFjNDk2NGEtNWViYi00ZDkyLWFmZTQtOTkyZTkzNDQ3OWU0LTExOTQ0NTc1MyIsInN1Ym5hbWUiOiJjOTFhYzUxYy1kOWE0LTQxMTUtYTk5NC00YTU3YTc0MzBlNTIiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiI3WThfdGNsY2hNN2U1YjBybFA1bGFWc1FUWVEiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjExOTAsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjExOTAsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY0NzkwLCJpYXQiOjE3MzE5NjExOTAsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6Ikx5cnRieGdBRUVzU3l4MVVEbF84c3pHVnhfOCJ9.GbeSZojqxzhdK6aVUDj-8ttz4L5IwPFyU_9JxKMr1TQ 2024-11-18 20:20:16,521 INFO Accept-Api-Version: 2024-11-18 20:20:16,521 INFO accept: */* 2024-11-18 20:20:16,521 INFO cookie: amlbcookie=01; 9b75a55744995d0=105UmbbkrNr9L7DENDubkmv322o.*AAJTSQACMDIAAlNLABxOV1hQQWNURDNRUzhOMGR6Qy9UTm0xWTBZeUE9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 20:20:16,521 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-18 20:20:16,521 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 20:20:16,521 INFO content-length: 0 2024-11-18 20:20:16,521 INFO cookies: 2024-11-18 20:20:16,521 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 20:20:16,521 INFO 9b75a55744995d0=105UmbbkrNr9L7DENDubkmv322o.*AAJTSQACMDIAAlNLABxOV1hQQWNURDNRUzhOMGR6Qy9UTm0xWTBZeUE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 20:20:16,521 INFO ========================= 2024-11-18 20:20:16,521 INFO HTTP response: 2024-11-18 20:20:16,521 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 20:20:16,521 INFO 20:19:51.069 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'restLoginInitiate' failed for user 430155: j.i.IOException: Premature close 2024-11-18 20:20:16,521 INFO 20:19:51.069 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 20:20:16,521 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 20:20:16,521 INFO Request: 2024-11-18 20:20:16,521 INFO restLoginInitiate: KO j.i.IOException: Premature close 2024-11-18 20:20:16,521 INFO ========================= 2024-11-18 20:20:16,521 INFO Session: 2024-11-18 20:20:16,521 INFO Session(idc.benchmark.Mix2024,430155,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, username -> user.62642, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@55e8375, fake-user-agent -> Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/64.0.3282.140 Safari/537.36 Edge/17.17134, password -> Pa_ssw0rd, fake-ip -> 64.107.178.234, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@734ebcb3),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007c40a469a0e0@29007dba,io.netty.channel.epoll.EpollEventLoop@650eab8) 2024-11-18 20:20:16,521 INFO ========================= 2024-11-18 20:20:16,521 INFO HTTP request: 2024-11-18 20:20:16,521 INFO POST https://openam-perf-benchmark.forgeblocks.com/am/json/realms/root/realms/alpha/authenticate?authIndexType=service&authIndexValue=Login 2024-11-18 20:20:16,521 INFO headers: 2024-11-18 20:20:16,521 INFO accept: application/json 2024-11-18 20:20:16,521 INFO content-type: application/json 2024-11-18 20:20:16,521 INFO user-agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/64.0.3282.140 Safari/537.36 Edge/17.17134 2024-11-18 20:20:16,521 INFO X-Forwarded-For: 64.107.178.234 2024-11-18 20:20:16,521 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 20:20:16,521 INFO content-length: 0 2024-11-18 20:20:16,521 INFO body:StringChunksRequestBody{charset=UTF-8, content=} 2024-11-18 20:20:16,521 INFO ========================= 2024-11-18 20:20:16,521 INFO HTTP response: 2024-11-18 20:20:16,521 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 20:20:16,521 INFO 20:19:51.069 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM version' failed for user 430035: j.i.IOException: Premature close 2024-11-18 20:20:16,521 INFO 20:19:51.070 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 20:20:16,521 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 20:20:16,521 INFO Request: 2024-11-18 20:20:16,521 INFO UI IDM version: KO j.i.IOException: Premature close 2024-11-18 20:20:16,521 INFO ========================= 2024-11-18 20:20:16,521 INFO Session: 2024-11-18 20:20:16,521 INFO Session(idc.benchmark.Mix2024,430035,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI0ZThiNWQwMC0yYmJmLTRlNmMtYWJkMi1kZWI2NWJiYWY3MzAiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTEyMDQ5NTU1OSIsInN1Ym5hbWUiOiI0ZThiNWQwMC0yYmJmLTRlNmMtYWJkMi1kZWI2NWJiYWY3MzAiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJEUGIzUmx3WVEzcmRNVk8zeTlORjlDNW1ac00iLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjExOTAsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjExOTAsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY0NzkwLCJpYXQiOjE3MzE5NjExOTAsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IkZTUGVsYnpod0hiR1NnMEV2RzFNaVY2RklzayJ9.XVfwf97XkMX_xNnxu1cVTqOpvmcP8VitDo3iK4EnwZE, username -> user.5067, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI0ZThiNWQwMC0yYmJmLTRlNmMtYWJkMi1kZWI2NWJiYWY3MzAiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZTY1ZTU0MzYtMzcwYS00MzI0LTg5ZmUtNDJhMjY3ZjljYzAyLTEyMDgzNzk3MCIsInN1Ym5hbWUiOiI0ZThiNWQwMC0yYmJmLTRlNmMtYWJkMi1kZWI2NWJiYWY3MzAiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJMLWQ0Zlp4eVBYeG9tU0J0Y0E1MndiZFY2bGMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjExOTAsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjExOTAsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY0NzkwLCJpYXQiOjE3MzE5NjExOTAsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IkxjSk9BR0VZZkNYckNETlVEdllYTWdOSWZRRSJ9._ovVcFF-JYAdFhL4ii8lmFYS1NAjMb-MkKxFKe65fcc, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@67685264, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> l3MqPy-kvAeSA1fCShgAuyH_az8, callbacks -> {"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiJiOGEzZjJjOC1iOThiLTRkZWMtYWIxZS0xYmM0MDRhZmY0ZmYiLCJhdXRoSW5kZXhWYWx1ZSI6IkxvZ2luIiwib3RrIjoiM3NjY21xYjlhNWUxaGdra3VsNDgzaHY5YnMiLCJhdXRoSW5kZXhUeXBlIjoic2VydmljZSIsInJlYWxtIjoiL2FscGhhIiwic2Vzc2lvbklkIjoiKkFBSlRTUUFDTURJQUJIUjVjR1VBQ0VwWFZGOUJWVlJJQUFKVE1RQUNNREUuKmV5SjBlWEFpT2lKS1YxUWlMQ0pqZEhraU9pSktWMVFpTENKaGJHY2lPaUpJVXpJMU5pSjkuWlhsS01HVllRV2xQYVVwTFZqRlJhVXhEU214aWJVMXBUMmxLUWsxVVNUUlJNRXBFVEZWb1ZFMXFWVEpKYVhkcFdWZDRia2xxYjJsYVIyeDVTVzR3TGk1UmQwcEVaMjlOVlZOa2FVNTFTbGRRT1VWRmIyWlJMalJLUzNwdVRYZG9iVlZPVkRBMGFrSjFTMHMxUkRaRmFXaHBTRFpoYUhoRWRFeENiV0pKYlhsRGJFWmtiV05yUkRreFJ6bHJXRlp5T0dSYVZtczNhR0l4Uld4SVIwdDFTRTA1WVZWS1FtOUNibkIxVkdzelZ6a3pSWFk1WVhsVU1XcGhWM0JmVDNWaVdsWjNaVEkwYlRkc2IxQkhhR2xRYkhsUlZFSnBUV2hvVTI5RGQwNUZRMjFzUWxGZldEWXhaRTlvYUdwelpUQllVelpNYkRscE5ERnBVMGhIZDA1dlNGRTRiSEI1YjNJek1WOUdUblYyY1dvMlMzTnZiMFJUVVVaT1YySjVTVXROT1hGdFEyNXhNbGwzUWxOV2NHcHpUVkJ3VVZkM1dqVlJZbTFyZWpnMlRGVjVNalpPTVRORFQwOXdNekZOVHpOek1FVlBXVkoyZVdsTVZsRkRNMXBPZGw5eWJVeEVWM05SZWpsR1F6ZFhhWFZSVURKdWRsSkRZVXQzYTFBM2NVRnBVbDlDTmpJdFNFbEpRWEphTmxVelNFVjNhWEpGUVd4Q1MyVTBha05SUW0xclpWZE5WekZLWVhwRk5qQlVhWFpLVmtSaGFHOVRTMGxmTnpRelVtaHVZVmhrVEZCelVsSm9TMVl3UkRkaVMwZHZaMHRTWHpKck16UXRMWGRzVmtOa1dGbHJlVGxuYmtSRVpIbEhTbUppUVdOSU5WSjJSRVZ4TWt0TldUTkVSV2hKYm1wNlprVnVVR1l4V1RsNlpUbEpiSFJuY2s5NVJsVjFUR0pNTUhObGJEUkZkbXhIUTNsMk1XRk5ZMGhpVjNsRGJGaFlVbXRuUVMxTVdteEtZM0pKTkVSRVNpMUdUVVJ4U0ZJMGNHVm5ObWh3UWxreVZVTkNhVjl0Y1VORU9HeEplR0puTm5oMGFHeFhYMU5OZWxCclNUTTFOVXgzZVdreGMwTkdZbk4zYTFFNE5uQm5Nak5GUkZrMGMxWTJOeTFYVFhsWlRXTnZUSFpVYVdoYWJraFZUekZ2UWxsTFVtVm5XVVUyVjBWd1pEUTNaWFY2VWs1RGFtNDVabmxIWW5vdFVHWklRVkJGT1dObk5HWkVaak0yT0ZGeVNFcGxVWGxxZFVaSlowMUlOakZtVGxkd2IxcEpRMVJWYm1WT05HVlBZWGQ2TkZGNFJtcDFhVVY1U2s1bldFdE9jMmRmUTFwbE5qVkNUbVl6VDJaM1RtbEpWa2xVZVVsU1dGaFBlamg2YWt0RVYyVXhZamRzZW1sVU5VcDVjRzVmTm5ObE5ra3pZVmh0TW5samNYaExSSHBGZW5sWFdFTTRjalZUUlVoNGNHeEZNRlZtZW0xUE1tMDFRbTFyWjNKRU9GaHVWV2s0ZG10ME1FNUNRbW81WVhveWVrMWlUbmxKWkRaS2IwNXFRVm81WkhWUU5VcFRUbVJzZVRsMWJpMW5Sbms0ZVZoeVMyMXpjazlVVWtWcU1VeEtUVE0xYVVsT1UyUkVSbk5HY21NNFZtOW9Oa1l3V0c5QmNIRlpPRFpSUlRkd2JEWjBkbUZIYVZCQ1ZWQnhPVXhJYjJOa2JWWk9lRTAzVVZselJVWXRhMjFLT1RVeGNuUmpPVlJaYWkwM2RGWkVPVTlvV0ZCbE9XVmlZbXRaVDNaMmRFUm5SVU5tUlhwb1QydDBhMWR6U1d0UU4xZDRkVXBoWVRsZk1YbEhVekk1WjFKMFp6QjBTVUZoVEhCU1IxSmFRazFxY21SM1IwSlJPR051V0RKWmRVOU1ObGR0WTJOTVdIbFBSVFZCWlRGV01qTkVURmszUVRkWWRFdGlUbXBPVm1OQ2JXUnRkR1pvTFZoU0xUWm1WMlZGVW5wcFdsZGFhblJETVRGa1ltOUhiWEZwZWkwMGMyVkVUSE4yWkZadGFsUjZkSGhLYkUxT2NtUmpVRmhvUzBkNU1EUm9TWE5aVlRSR1dqSnJjRlY0WDBwU2FYSllXV0Z6TlZnME1tbDJOMEpPZVZkMFRFaDJUWFJ5UWw5SmMxWlFlRGg0WTB0TlNVdE1RVGhHYmxORGNta3lNMVpTWTNKSFdsWm5TVnBxTldZNGRFaE5WMnByUlUxM1VuaHllalp3VGpOUGIyUXlMV3R2Vm1JNFpYWjVPVWczVG1KSGNsRXRiRVJCU0doV1gzTm5SSGh5Y0RKVmJqQlpkbVJNU1VoWWEwUndSRFJTWnpZMFkwSnRSbFpIUjBWdFFXNVZla05XWlRSTVQxcDRNWGxWT1UxcWRIQnRNbkIwU1RCZlFVUmxUMFZxTkRkQ1VGQkJha2xrVjBGaFJ6WjJkakpGWTI5Q1lWQlViVVZZTkhGYVRtbEtUbnBGTVdsQ05rTjJjVUZ5ZDBZM1pWY3lTMEZDVG10NGJUbG1UR2hhY2pJNGQyVnZkVWcwWWxjMGNHZHdSRmxXVmtJMk5VRnFjR2RtUWpObE0yTlRObkZxTWxJM1JtUnBTbkJVWmpOa05WbFpaMGhzYVdwWVRHbHJUSEZ1YTA5alYxTjRObmhXVFV0WE9FRlpkV1l0Vm1GUGVtczBVelIwU0VndFRWWmZiVUUxZGpReWNUSXdMVGxxYm1GMWEweEZNV1J2UW1sd1VXNTNkV1UwYTNCblMwWkZkMDFZY1Vjd2FtMXVUSFZrUWpKNGVXVndhRGhsV1U1T1RrSkJkRWx6Vms5TE0yUm5jMUYzZWxSTFEySlpSSEV4YzFabFVFMWpNSFUxZGxWYVRXUTNjVVZvVDA1a2VVTk9VblU1WlhSdFJsZ3hUVzFJUlZaU1VHcHlSMncxYmt3dGJEVXlObk5qWWt4cVF6UjZjVmRtUjNaeU5YbzNjblkxY21aSmRGRTNabTE1Y2pkb2RFMW5VbE40T0hWcGNERTVaRzlGUnpWQk0wMHpkMWwyTVZaMWRFa3pOM0pmYlZKZk9YbHdVM3BPY0ZCMFFWSjBjVW93ZVdFNVVrZHVhMmMzU1d4T2NqWlRTVnBuUlZsV2MxWjZPV2h3WVhCQ1FqRTNhVWxIZVdVNVlqVXlOR1l3UkdOcmFrbERTRGQ2Y25WTGJEaFRWMGRpTjI0MWRGWlZaMjh5VmkxVVMyUlVha1JsT0VaSFYxOUljV3BGYkRsR1MzQm5NRGRpU21wRmQwUkJUVzFzYTNKaWRGRkRUVmRRTjI4MVpVOXpWblZOY2pWTU1tRlpaVU5DUkdJelNqbGZhWE53YVZSTGJtNXVlRkJJZDJsdVFsUXpUa2w1UkhKemVtdDFVMGw2TXpObE9FcGxTMVprTnkxR1kzbFVPVkF0ZDBsd1RVRkNTRTlzTW1OU1psOVdlbTFCU1Vac2NYSndaWGQxYmpjeVZrMXdZaloyVm14U1ZIQmZka2MwYlUxWk9YQkZWV3RoZWtodk9VcDBaREJCYkhac1JqVkdkblJGVEdKVmJVNUtaWFkwZWtGV2F6TlJjMngxUlMxc05IRmpZMlZhV0hoalQxUlZPVll6TW5VMFpVSkpiRVJRVmxrM00zUmZYMHRJWVZnMlJrdHBWSGt4YW1Wak1XZGFPR0ZmZVZRM1NETk9hM1JxYmt3M1RtMHRVVWhsWVdsR1RqUlNlVlJJY2pRdFJsQlpTVVptTWtSVVRVdEVURUZtZWxoa1dsVjNhMjVTU2w5T1IyZHVaeTVPTlVOSVkyZGhWbkE1U1dKWk5qTjRWVmRVTjBWMy5MTmJ5a2xIWnBlb2hidzlvelhOMTlQSlRSb0VfelJBemw5ZHo1ZDVhcFA4IiwiZXhwIjoxNzMxOTYxNDkwLCJpYXQiOjE3MzE5NjExOTB9.DXbG9gXHDiTfoKUZr9ICEjwUl66jW53mxFJm3Q_7Ai8","callbacks":[{"type":"BooleanAttributeInputCallback","output":[{"name":"name","value":"preferences/updates"},{"name":"prompt","value":"Send me news and updates"},{"name":"required","value":false},{"name":"policies","value":{}},{"name":"failedPolicies","value":[]},{"name":"validateOnly","value":false},{"name":"value","value":false}],"input":[{"name":"IDToken1","value":true},{"name":"IDToken1validateOnly","value":false}],"_id":2},{"type":"BooleanAttributeInputCallback","output":[{"name":"name","value":"preferences/marketing"},{"name":"prompt","value":"Send me special offers and services"},{"name":"required","value":false},{"name":"policies","value":{}},{"name":"failedPolicies","value":[]},{"name":"validateOnly","value":false},{"name":"value","value":false}],"input":[{"name":"IDToken2","value":true},{"name":"IDToken2validateOnly","value":false}],"_id":3}],"header":"Please select your preferences"}, 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,1731961190466), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=L8US3BJYu4Km-UZD0Xv5iKmVb7c.*AAJTSQACMDIAAlNLABx4dVV0cm1GL1ZyQktHS0x4Mkxqd1RFMnpQREE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*,true,false,1731961190466))), user_uuid -> 4e8b5d00-2bbf-4e6c-abd2-deb65bbaf730, password -> Pa_ssw0rd, challenge -> hWzXR5O6Nrm4whfCJ3zwc5IA2dXYJceJtWP4OmkFU2Q, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 42.42.42.42, verifier -> LTI0Nzc2NDc4OTQ2MjM5NTIwNDk, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@d18acf1, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@3f07b110, tokenId -> L8US3BJYu4Km-UZD0Xv5iKmVb7c.*AAJTSQACMDIAAlNLABx4dVV0cm1GL1ZyQktHS0x4Mkxqd1RFMnpQREE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (iPhone; CPU iPhone OS 13_2_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/13.0.3 Mobile/15E148 Safari/604.1),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007c40a469a0e0@29007dba,io.netty.channel.epoll.EpollEventLoop@12dae582) 2024-11-18 20:20:16,521 INFO ========================= 2024-11-18 20:20:16,521 INFO HTTP request: 2024-11-18 20:20:16,521 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/version 2024-11-18 20:20:16,521 INFO headers: 2024-11-18 20:20:16,521 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-18 20:20:16,522 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 20:20:16,522 INFO Accept-Api-Version: 2024-11-18 20:20:16,522 INFO accept: */* 2024-11-18 20:20:16,522 INFO cookie: amlbcookie=01; 9b75a55744995d0=L8US3BJYu4Km-UZD0Xv5iKmVb7c.*AAJTSQACMDIAAlNLABx4dVV0cm1GL1ZyQktHS0x4Mkxqd1RFMnpQREE9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 20:20:16,522 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 20:20:16,522 INFO cookies: 2024-11-18 20:20:16,522 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 20:20:16,522 INFO 9b75a55744995d0=L8US3BJYu4Km-UZD0Xv5iKmVb7c.*AAJTSQACMDIAAlNLABx4dVV0cm1GL1ZyQktHS0x4Mkxqd1RFMnpQREE9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 20:20:16,522 INFO ========================= 2024-11-18 20:20:16,522 INFO HTTP response: 2024-11-18 20:20:16,522 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 20:20:16,522 INFO 20:19:51.070 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 430035: j.i.IOException: Premature close 2024-11-18 20:20:16,522 INFO 20:19:51.070 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 20:20:16,522 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 20:20:16,522 INFO Request: 2024-11-18 20:20:16,522 INFO UI IDM login: KO j.i.IOException: Premature close 2024-11-18 20:20:16,522 INFO ========================= 2024-11-18 20:20:16,522 INFO Session: 2024-11-18 20:20:16,522 INFO Session(idc.benchmark.Mix2024,430035,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI0ZThiNWQwMC0yYmJmLTRlNmMtYWJkMi1kZWI2NWJiYWY3MzAiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTEyMDQ5NTU1OSIsInN1Ym5hbWUiOiI0ZThiNWQwMC0yYmJmLTRlNmMtYWJkMi1kZWI2NWJiYWY3MzAiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJEUGIzUmx3WVEzcmRNVk8zeTlORjlDNW1ac00iLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjExOTAsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjExOTAsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY0NzkwLCJpYXQiOjE3MzE5NjExOTAsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IkZTUGVsYnpod0hiR1NnMEV2RzFNaVY2RklzayJ9.XVfwf97XkMX_xNnxu1cVTqOpvmcP8VitDo3iK4EnwZE, username -> user.5067, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI0ZThiNWQwMC0yYmJmLTRlNmMtYWJkMi1kZWI2NWJiYWY3MzAiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZTY1ZTU0MzYtMzcwYS00MzI0LTg5ZmUtNDJhMjY3ZjljYzAyLTEyMDgzNzk3MCIsInN1Ym5hbWUiOiI0ZThiNWQwMC0yYmJmLTRlNmMtYWJkMi1kZWI2NWJiYWY3MzAiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJMLWQ0Zlp4eVBYeG9tU0J0Y0E1MndiZFY2bGMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjExOTAsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjExOTAsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY0NzkwLCJpYXQiOjE3MzE5NjExOTAsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IkxjSk9BR0VZZkNYckNETlVEdllYTWdOSWZRRSJ9._ovVcFF-JYAdFhL4ii8lmFYS1NAjMb-MkKxFKe65fcc, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@67685264, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> l3MqPy-kvAeSA1fCShgAuyH_az8, callbacks -> {"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiJiOGEzZjJjOC1iOThiLTRkZWMtYWIxZS0xYmM0MDRhZmY0ZmYiLCJhdXRoSW5kZXhWYWx1ZSI6IkxvZ2luIiwib3RrIjoiM3NjY21xYjlhNWUxaGdra3VsNDgzaHY5YnMiLCJhdXRoSW5kZXhUeXBlIjoic2VydmljZSIsInJlYWxtIjoiL2FscGhhIiwic2Vzc2lvbklkIjoiKkFBSlRTUUFDTURJQUJIUjVjR1VBQ0VwWFZGOUJWVlJJQUFKVE1RQUNNREUuKmV5SjBlWEFpT2lKS1YxUWlMQ0pqZEhraU9pSktWMVFpTENKaGJHY2lPaUpJVXpJMU5pSjkuWlhsS01HVllRV2xQYVVwTFZqRlJhVXhEU214aWJVMXBUMmxLUWsxVVNUUlJNRXBFVEZWb1ZFMXFWVEpKYVhkcFdWZDRia2xxYjJsYVIyeDVTVzR3TGk1UmQwcEVaMjlOVlZOa2FVNTFTbGRRT1VWRmIyWlJMalJLUzNwdVRYZG9iVlZPVkRBMGFrSjFTMHMxUkRaRmFXaHBTRFpoYUhoRWRFeENiV0pKYlhsRGJFWmtiV05yUkRreFJ6bHJXRlp5T0dSYVZtczNhR0l4Uld4SVIwdDFTRTA1WVZWS1FtOUNibkIxVkdzelZ6a3pSWFk1WVhsVU1XcGhWM0JmVDNWaVdsWjNaVEkwYlRkc2IxQkhhR2xRYkhsUlZFSnBUV2hvVTI5RGQwNUZRMjFzUWxGZldEWXhaRTlvYUdwelpUQllVelpNYkRscE5ERnBVMGhIZDA1dlNGRTRiSEI1YjNJek1WOUdUblYyY1dvMlMzTnZiMFJUVVVaT1YySjVTVXROT1hGdFEyNXhNbGwzUWxOV2NHcHpUVkJ3VVZkM1dqVlJZbTFyZWpnMlRGVjVNalpPTVRORFQwOXdNekZOVHpOek1FVlBXVkoyZVdsTVZsRkRNMXBPZGw5eWJVeEVWM05SZWpsR1F6ZFhhWFZSVURKdWRsSkRZVXQzYTFBM2NVRnBVbDlDTmpJdFNFbEpRWEphTmxVelNFVjNhWEpGUVd4Q1MyVTBha05SUW0xclpWZE5WekZLWVhwRk5qQlVhWFpLVmtSaGFHOVRTMGxmTnpRelVtaHVZVmhrVEZCelVsSm9TMVl3UkRkaVMwZHZaMHRTWHpKck16UXRMWGRzVmtOa1dGbHJlVGxuYmtSRVpIbEhTbUppUVdOSU5WSjJSRVZ4TWt0TldUTkVSV2hKYm1wNlprVnVVR1l4V1RsNlpUbEpiSFJuY2s5NVJsVjFUR0pNTUhObGJEUkZkbXhIUTNsMk1XRk5ZMGhpVjNsRGJGaFlVbXRuUVMxTVdteEtZM0pKTkVSRVNpMUdUVVJ4U0ZJMGNHVm5ObWh3UWxreVZVTkNhVjl0Y1VORU9HeEplR0puTm5oMGFHeFhYMU5OZWxCclNUTTFOVXgzZVdreGMwTkdZbk4zYTFFNE5uQm5Nak5GUkZrMGMxWTJOeTFYVFhsWlRXTnZUSFpVYVdoYWJraFZUekZ2UWxsTFVtVm5XVVUyVjBWd1pEUTNaWFY2VWs1RGFtNDVabmxIWW5vdFVHWklRVkJGT1dObk5HWkVaak0yT0ZGeVNFcGxVWGxxZFVaSlowMUlOakZtVGxkd2IxcEpRMVJWYm1WT05HVlBZWGQ2TkZGNFJtcDFhVVY1U2s1bldFdE9jMmRmUTFwbE5qVkNUbVl6VDJaM1RtbEpWa2xVZVVsU1dGaFBlamg2YWt0RVYyVXhZamRzZW1sVU5VcDVjRzVmTm5ObE5ra3pZVmh0TW5samNYaExSSHBGZW5sWFdFTTRjalZUUlVoNGNHeEZNRlZtZW0xUE1tMDFRbTFyWjNKRU9GaHVWV2s0ZG10ME1FNUNRbW81WVhveWVrMWlUbmxKWkRaS2IwNXFRVm81WkhWUU5VcFRUbVJzZVRsMWJpMW5Sbms0ZVZoeVMyMXpjazlVVWtWcU1VeEtUVE0xYVVsT1UyUkVSbk5HY21NNFZtOW9Oa1l3V0c5QmNIRlpPRFpSUlRkd2JEWjBkbUZIYVZCQ1ZWQnhPVXhJYjJOa2JWWk9lRTAzVVZselJVWXRhMjFLT1RVeGNuUmpPVlJaYWkwM2RGWkVPVTlvV0ZCbE9XVmlZbXRaVDNaMmRFUm5SVU5tUlhwb1QydDBhMWR6U1d0UU4xZDRkVXBoWVRsZk1YbEhVekk1WjFKMFp6QjBTVUZoVEhCU1IxSmFRazFxY21SM1IwSlJPR051V0RKWmRVOU1ObGR0WTJOTVdIbFBSVFZCWlRGV01qTkVURmszUVRkWWRFdGlUbXBPVm1OQ2JXUnRkR1pvTFZoU0xUWm1WMlZGVW5wcFdsZGFhblJETVRGa1ltOUhiWEZwZWkwMGMyVkVUSE4yWkZadGFsUjZkSGhLYkUxT2NtUmpVRmhvUzBkNU1EUm9TWE5aVlRSR1dqSnJjRlY0WDBwU2FYSllXV0Z6TlZnME1tbDJOMEpPZVZkMFRFaDJUWFJ5UWw5SmMxWlFlRGg0WTB0TlNVdE1RVGhHYmxORGNta3lNMVpTWTNKSFdsWm5TVnBxTldZNGRFaE5WMnByUlUxM1VuaHllalp3VGpOUGIyUXlMV3R2Vm1JNFpYWjVPVWczVG1KSGNsRXRiRVJCU0doV1gzTm5SSGh5Y0RKVmJqQlpkbVJNU1VoWWEwUndSRFJTWnpZMFkwSnRSbFpIUjBWdFFXNVZla05XWlRSTVQxcDRNWGxWT1UxcWRIQnRNbkIwU1RCZlFVUmxUMFZxTkRkQ1VGQkJha2xrVjBGaFJ6WjJkakpGWTI5Q1lWQlViVVZZTkhGYVRtbEtUbnBGTVdsQ05rTjJjVUZ5ZDBZM1pWY3lTMEZDVG10NGJUbG1UR2hhY2pJNGQyVnZkVWcwWWxjMGNHZHdSRmxXVmtJMk5VRnFjR2RtUWpObE0yTlRObkZxTWxJM1JtUnBTbkJVWmpOa05WbFpaMGhzYVdwWVRHbHJUSEZ1YTA5alYxTjRObmhXVFV0WE9FRlpkV1l0Vm1GUGVtczBVelIwU0VndFRWWmZiVUUxZGpReWNUSXdMVGxxYm1GMWEweEZNV1J2UW1sd1VXNTNkV1UwYTNCblMwWkZkMDFZY1Vjd2FtMXVUSFZrUWpKNGVXVndhRGhsV1U1T1RrSkJkRWx6Vms5TE0yUm5jMUYzZWxSTFEySlpSSEV4YzFabFVFMWpNSFUxZGxWYVRXUTNjVVZvVDA1a2VVTk9VblU1WlhSdFJsZ3hUVzFJUlZaU1VHcHlSMncxYmt3dGJEVXlObk5qWWt4cVF6UjZjVmRtUjNaeU5YbzNjblkxY21aSmRGRTNabTE1Y2pkb2RFMW5VbE40T0hWcGNERTVaRzlGUnpWQk0wMHpkMWwyTVZaMWRFa3pOM0pmYlZKZk9YbHdVM3BPY0ZCMFFWSjBjVW93ZVdFNVVrZHVhMmMzU1d4T2NqWlRTVnBuUlZsV2MxWjZPV2h3WVhCQ1FqRTNhVWxIZVdVNVlqVXlOR1l3UkdOcmFrbERTRGQ2Y25WTGJEaFRWMGRpTjI0MWRGWlZaMjh5VmkxVVMyUlVha1JsT0VaSFYxOUljV3BGYkRsR1MzQm5NRGRpU21wRmQwUkJUVzFzYTNKaWRGRkRUVmRRTjI4MVpVOXpWblZOY2pWTU1tRlpaVU5DUkdJelNqbGZhWE53YVZSTGJtNXVlRkJJZDJsdVFsUXpUa2w1UkhKemVtdDFVMGw2TXpObE9FcGxTMVprTnkxR1kzbFVPVkF0ZDBsd1RVRkNTRTlzTW1OU1psOVdlbTFCU1Vac2NYSndaWGQxYmpjeVZrMXdZaloyVm14U1ZIQmZka2MwYlUxWk9YQkZWV3RoZWtodk9VcDBaREJCYkhac1JqVkdkblJGVEdKVmJVNUtaWFkwZWtGV2F6TlJjMngxUlMxc05IRmpZMlZhV0hoalQxUlZPVll6TW5VMFpVSkpiRVJRVmxrM00zUmZYMHRJWVZnMlJrdHBWSGt4YW1Wak1XZGFPR0ZmZVZRM1NETk9hM1JxYmt3M1RtMHRVVWhsWVdsR1RqUlNlVlJJY2pRdFJsQlpTVVptTWtSVVRVdEVURUZtZWxoa1dsVjNhMjVTU2w5T1IyZHVaeTVPTlVOSVkyZGhWbkE1U1dKWk5qTjRWVmRVTjBWMy5MTmJ5a2xIWnBlb2hidzlvelhOMTlQSlRSb0VfelJBemw5ZHo1ZDVhcFA4IiwiZXhwIjoxNzMxOTYxNDkwLCJpYXQiOjE3MzE5NjExOTB9.DXbG9gXHDiTfoKUZr9ICEjwUl66jW53mxFJm3Q_7Ai8","callbacks":[{"type":"BooleanAttributeInputCallback","output":[{"name":"name","value":"preferences/updates"},{"name":"prompt","value":"Send me news and updates"},{"name":"required","value":false},{"name":"policies","value":{}},{"name":"failedPolicies","value":[]},{"name":"validateOnly","value":false},{"name":"value","value":false}],"input":[{"name":"IDToken1","value":true},{"name":"IDToken1validateOnly","value":false}],"_id":2},{"type":"BooleanAttributeInputCallback","output":[{"name":"name","value":"preferences/marketing"},{"name":"prompt","value":"Send me special offers and services"},{"name":"required","value":false},{"name":"policies","value":{}},{"name":"failedPolicies","value":[]},{"name":"validateOnly","value":false},{"name":"value","value":false}],"input":[{"name":"IDToken2","value":true},{"name":"IDToken2validateOnly","value":false}],"_id":3}],"header":"Please select your preferences"}, 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,1731961190466), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=L8US3BJYu4Km-UZD0Xv5iKmVb7c.*AAJTSQACMDIAAlNLABx4dVV0cm1GL1ZyQktHS0x4Mkxqd1RFMnpQREE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*,true,false,1731961190466))), user_uuid -> 4e8b5d00-2bbf-4e6c-abd2-deb65bbaf730, password -> Pa_ssw0rd, challenge -> hWzXR5O6Nrm4whfCJ3zwc5IA2dXYJceJtWP4OmkFU2Q, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 42.42.42.42, verifier -> LTI0Nzc2NDc4OTQ2MjM5NTIwNDk, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@d18acf1, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@3f07b110, tokenId -> L8US3BJYu4Km-UZD0Xv5iKmVb7c.*AAJTSQACMDIAAlNLABx4dVV0cm1GL1ZyQktHS0x4Mkxqd1RFMnpQREE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (iPhone; CPU iPhone OS 13_2_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/13.0.3 Mobile/15E148 Safari/604.1),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007c40a469a0e0@29007dba,io.netty.channel.epoll.EpollEventLoop@12dae582) 2024-11-18 20:20:16,522 INFO ========================= 2024-11-18 20:20:16,522 INFO HTTP request: 2024-11-18 20:20:16,522 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-18 20:20:16,522 INFO headers: 2024-11-18 20:20:16,522 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-18 20:20:16,522 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 20:20:16,522 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI0ZThiNWQwMC0yYmJmLTRlNmMtYWJkMi1kZWI2NWJiYWY3MzAiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZTY1ZTU0MzYtMzcwYS00MzI0LTg5ZmUtNDJhMjY3ZjljYzAyLTEyMDgzNzk3MCIsInN1Ym5hbWUiOiI0ZThiNWQwMC0yYmJmLTRlNmMtYWJkMi1kZWI2NWJiYWY3MzAiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJMLWQ0Zlp4eVBYeG9tU0J0Y0E1MndiZFY2bGMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NjExOTAsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NjExOTAsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTY0NzkwLCJpYXQiOjE3MzE5NjExOTAsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IkxjSk9BR0VZZkNYckNETlVEdllYTWdOSWZRRSJ9._ovVcFF-JYAdFhL4ii8lmFYS1NAjMb-MkKxFKe65fcc 2024-11-18 20:20:16,522 INFO Accept-Api-Version: 2024-11-18 20:20:16,522 INFO accept: */* 2024-11-18 20:20:16,522 INFO cookie: amlbcookie=01; 9b75a55744995d0=L8US3BJYu4Km-UZD0Xv5iKmVb7c.*AAJTSQACMDIAAlNLABx4dVV0cm1GL1ZyQktHS0x4Mkxqd1RFMnpQREE9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 20:20:16,522 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-18 20:20:16,522 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 20:20:16,522 INFO content-length: 0 2024-11-18 20:20:16,522 INFO cookies: 2024-11-18 20:20:16,522 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 20:20:16,522 INFO 9b75a55744995d0=L8US3BJYu4Km-UZD0Xv5iKmVb7c.*AAJTSQACMDIAAlNLABx4dVV0cm1GL1ZyQktHS0x4Mkxqd1RFMnpQREE9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 20:20:16,522 INFO ========================= 2024-11-18 20:20:16,522 INFO HTTP response: 2024-11-18 20:20:16,522 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 20:20:16,522 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 20:20:16,522 INFO [INFO] BUILD SUCCESS 2024-11-18 20:20:16,522 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 20:20:16,522 INFO [INFO] Total time: 01:00 h 2024-11-18 20:20:16,522 INFO [INFO] Finished at: 2024-11-18T20:19:51Z 2024-11-18 20:20:16,522 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 20:20:16,522 INFO 2024-11-18 20:19:51,194 INFO Gatling simulation run ended successfully 2024-11-18 20:20:16,522 INFO 2024-11-18 20:19:51,194 DEBUG No archive name provided. No archive will be created 2024-11-18 20:20:16,522 INFO ________________________________________________________________________________ [2024-11-18 20:20:16] benchmark-120 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-18 20:20:16,522 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: 2024-11-18 20:20:16,522 DEBUG ----- output ----- 2024-11-18 20:20:16,523 DEBUG 2024-11-18 20:20:16,523 INFO ________________________________________________________________________________ [2024-11-18 20:20:16] 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 ________________________________________________________________________________ [2024-11-18 20:20:16] benchmark-120 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-18 20:20:16] benchmark-120 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped