--Task-- name: benchmark-90 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-70'] preceding_task: benchmark-70 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self90-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 90} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-14 05:29:22] benchmark-90 pre : N/A ________________________________________________________________________________ 2024-11-14 05:29:22 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-14 05:29:22 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-90/stdout.txt Pod log : /results/orders/benchmark-90 2024-11-14 05:29:22,994 INFO 2024-11-14 05:29:22,994 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-14 05:29:22,994 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 05:29:23,269 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 05:29:23,269 INFO [loop_until]: OK (rc = 0) 2024-11-14 05:29:23,269 DEBUG --- stdout --- 2024-11-14 05:29:23,269 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 05:29:23,269 DEBUG --- stderr --- 2024-11-14 05:29:23,269 DEBUG ________________________________________________________________________________ [2024-11-14 05:29:23] benchmark-90 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-14 05:29:23,269 INFO 2024-11-14 05:29:23,269 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-14 05:29:23,269 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 05:29:23,544 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 05:29:23,544 INFO [loop_until]: OK (rc = 0) 2024-11-14 05:29:23,544 DEBUG --- stdout --- 2024-11-14 05:29:23,544 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 05:29:23,544 DEBUG --- stderr --- 2024-11-14 05:29:23,544 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-90" --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=self90- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=90 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-14 05:29:23] benchmark-90 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-14 05:29:23,545 INFO 2024-11-14 05:29:23,545 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-14 05:29:23,545 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 05:29:23,844 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 05:29:23,844 INFO [loop_until]: OK (rc = 0) 2024-11-14 05:29:23,844 DEBUG --- stdout --- 2024-11-14 05:29:23,844 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 05:29:23,844 DEBUG --- stderr --- 2024-11-14 05:29:23,845 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/order.json { "name": "benchmark-90", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-90\" --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=self90- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=90 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-90" } ________________________________________________________________________________ [2024-11-14 05:29:23] benchmark-90 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-14 05:29:23,847 INFO 2024-11-14 05:29:23,848 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-14 05:29:23,848 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-14 05:29:24,064 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-14 05:29:24,064 INFO [loop_until]: OK (rc = 0) 2024-11-14 05:29:24,064 DEBUG --- stdout --- 2024-11-14 05:29:24,064 DEBUG WAITING 2024-11-14 05:29:24,064 DEBUG --- stderr --- 2024-11-14 05:29:24,064 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-11-14 05:29:24] benchmark-90 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-14 05:29:24,064 INFO 2024-11-14 05:29:24,064 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-14 05:29:24,064 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 05:29:24,339 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 05:29:24,339 INFO [loop_until]: OK (rc = 0) 2024-11-14 05:29:24,339 DEBUG --- stdout --- 2024-11-14 05:29:24,339 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 05:29:24,339 DEBUG --- stderr --- 2024-11-14 05:29:24,339 DEBUG 2024-11-14 05:29:24,339 INFO 2024-11-14 05:29:24,339 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/05_benchmark-90/order.json overseer-0-6644bb6697-rchxr:/results/orders/order.json 2024-11-14 05:29:24,339 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-14 05:29:25,691 INFO [loop_until]: OK (rc = 0) 2024-11-14 05:29:25,691 DEBUG --- stdout --- 2024-11-14 05:29:25,691 DEBUG 2024-11-14 05:29:25,691 DEBUG --- stderr --- 2024-11-14 05:29:25,691 DEBUG Order has been successfully forwarded to overseer 2024-11-14 05:29:25 - INFO: Check if Gatling simulation started successfully 2024-11-14 05:29:25,692 INFO 2024-11-14 05:29:25,692 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-14 05:29:25,692 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 05:29:25,968 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 05:29:25,968 INFO [loop_until]: OK (rc = 0) 2024-11-14 05:29:25,968 DEBUG --- stdout --- 2024-11-14 05:29:25,968 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 05:29:25,968 DEBUG --- stderr --- 2024-11-14 05:29:25,968 DEBUG 2024-11-14 05:29:25,968 INFO 2024-11-14 05:29:25,968 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-90/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-14 05:29:25,968 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-14 05:29:26,688 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-14 05:29:32,416 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-14 05:29:38,135 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-14 05:29:38,135 INFO [loop_until]: OK (rc = 0) 2024-11-14 05:29:38,135 DEBUG --- stdout --- 2024-11-14 05:29:38,135 DEBUG 2024-11-14 05:29:26,225 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp1_yfcs72 2024-11-14 05:29:26,227 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-90" -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=self90- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=90 -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/tmp1_yfcs72/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/tmp1_yfcs72/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. 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-14 05:29:38,135 DEBUG --- stderr --- 2024-11-14 05:29:38,135 DEBUG ________________________________________________________________________________ [2024-11-14 05:29:38] benchmark-90 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m. Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 02s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 03s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 04s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 05s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 06s, retry Order is running since 46m 06s, retry Order is running since 48m 07s, retry Order is running since 50m 07s, retry Order is running since 52m 07s, retry Order is running since 54m 08s, retry Order is running since 56m 08s, retry Order is running since 58m 08s, retry PASS : Order stopped by itself after 1h and before timeout (1h 12m) ________________________________________________________________________________ [2024-11-14 06:29:47] benchmark-90 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-14 06:29:47,068 INFO 2024-11-14 06:29:47,068 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-14 06:29:47,068 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:47,370 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:47,370 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:47,371 DEBUG --- stdout --- 2024-11-14 06:29:47,371 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:47,371 DEBUG --- stderr --- 2024-11-14 06:29:47,371 DEBUG 2024-11-14 06:29:47,371 INFO 2024-11-14 06:29:47,371 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-14 06:29:47,371 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-14 06:29:48,146 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:48,146 DEBUG --- stdout --- 2024-11-14 06:29:48,147 DEBUG 2024-11-14 06:29:48,147 DEBUG --- stderr --- 2024-11-14 06:29:48,147 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-14 06:29:48] benchmark-90 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-14 06:29:48 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/order.json 2024-11-14 06:29:48,147 INFO 2024-11-14 06:29:48,147 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-14 06:29:48,147 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:48,424 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:48,424 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:48,424 DEBUG --- stdout --- 2024-11-14 06:29:48,424 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:48,424 DEBUG --- stderr --- 2024-11-14 06:29:48,424 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-14 06:29:48,424 INFO 2024-11-14 06:29:48,424 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-14 06:29:48,424 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:48,704 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:48,704 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:48,704 DEBUG --- stdout --- 2024-11-14 06:29:48,704 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:48,704 DEBUG --- stderr --- 2024-11-14 06:29:48,704 DEBUG 2024-11-14 06:29:48,705 INFO 2024-11-14 06:29:48,705 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-90/rc.txt" ]; then echo "/results/orders/benchmark-90/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-90/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-90/rc.txt file found" 2024-11-14 06:29:48,705 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-14 06:29:49,507 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-14 06:29:49,507 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:49,507 DEBUG --- stdout --- 2024-11-14 06:29:49,507 DEBUG /results/orders/benchmark-90/rc.txt file found 2024-11-14 06:29:49,507 DEBUG --- stderr --- 2024-11-14 06:29:49,507 DEBUG 2024-11-14 06:29:49,508 INFO 2024-11-14 06:29:49,508 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-14 06:29:49,508 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:49,785 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:49,785 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:49,785 DEBUG --- stdout --- 2024-11-14 06:29:49,785 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:49,785 DEBUG --- stderr --- 2024-11-14 06:29:49,785 DEBUG 2024-11-14 06:29:49,785 INFO 2024-11-14 06:29:49,785 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-90/rc.txt 2024-11-14 06:29:50,576 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-14 06:29:50,577 DEBUG --- stdout --- 2024-11-14 06:29:50,577 DEBUG 4 /results/orders/benchmark-90/rc.txt 2024-11-14 06:29:50,577 DEBUG --- stderr --- 2024-11-14 06:29:50,577 DEBUG 2024-11-14 06:29:50,577 INFO - Download file (size 4k) 2024-11-14 06:29:50,577 INFO 2024-11-14 06:29:50,577 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-14 06:29:50,577 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:50,853 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:50,853 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:50,853 DEBUG --- stdout --- 2024-11-14 06:29:50,853 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:50,853 DEBUG --- stderr --- 2024-11-14 06:29:50,853 DEBUG source : /results/orders/benchmark-90/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/rc.txt 2024-11-14 06:29:50,854 INFO 2024-11-14 06:29:50,854 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-14 06:29:50,854 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:51,129 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:51,129 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:51,129 DEBUG --- stdout --- 2024-11-14 06:29:51,129 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:51,129 DEBUG --- stderr --- 2024-11-14 06:29:51,129 DEBUG 2024-11-14 06:29:51,130 INFO 2024-11-14 06:29:51,130 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-90/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/rc.txt 2024-11-14 06:29:51,130 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-14 06:29:51,887 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:51,887 DEBUG --- stdout --- 2024-11-14 06:29:51,887 DEBUG tar: Removing leading `/' from member names 2024-11-14 06:29:51,887 DEBUG --- stderr --- 2024-11-14 06:29:51,887 DEBUG 2024-11-14 06:29:51,888 INFO 2024-11-14 06:29:51,888 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-14 06:29:51,888 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:52,165 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:52,165 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:52,165 DEBUG --- stdout --- 2024-11-14 06:29:52,165 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:52,165 DEBUG --- stderr --- 2024-11-14 06:29:52,165 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-14 06:29:52,166 INFO 2024-11-14 06:29:52,166 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-14 06:29:52,166 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:52,443 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:52,443 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:52,443 DEBUG --- stdout --- 2024-11-14 06:29:52,443 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:52,443 DEBUG --- stderr --- 2024-11-14 06:29:52,443 DEBUG 2024-11-14 06:29:52,444 INFO 2024-11-14 06:29:52,444 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-90/stderr.txt" ]; then echo "/results/orders/benchmark-90/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-90/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-90/stderr.txt file found" 2024-11-14 06:29:52,444 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-14 06:29:53,234 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-14 06:29:53,234 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:53,234 DEBUG --- stdout --- 2024-11-14 06:29:53,234 DEBUG /results/orders/benchmark-90/stderr.txt file found 2024-11-14 06:29:53,234 DEBUG --- stderr --- 2024-11-14 06:29:53,234 DEBUG 2024-11-14 06:29:53,235 INFO 2024-11-14 06:29:53,235 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-14 06:29:53,235 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:53,511 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:53,511 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:53,511 DEBUG --- stdout --- 2024-11-14 06:29:53,511 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:53,511 DEBUG --- stderr --- 2024-11-14 06:29:53,511 DEBUG 2024-11-14 06:29:53,511 INFO 2024-11-14 06:29:53,511 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-90/stderr.txt 2024-11-14 06:29:54,339 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-14 06:29:54,339 DEBUG --- stdout --- 2024-11-14 06:29:54,339 DEBUG 0 /results/orders/benchmark-90/stderr.txt 2024-11-14 06:29:54,339 DEBUG --- stderr --- 2024-11-14 06:29:54,339 DEBUG 2024-11-14 06:29:54,339 INFO - Download file (size 0k) 2024-11-14 06:29:54,340 INFO 2024-11-14 06:29:54,340 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-14 06:29:54,340 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:54,617 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:54,617 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:54,617 DEBUG --- stdout --- 2024-11-14 06:29:54,617 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:54,617 DEBUG --- stderr --- 2024-11-14 06:29:54,617 DEBUG source : /results/orders/benchmark-90/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/stderr.txt 2024-11-14 06:29:54,618 INFO 2024-11-14 06:29:54,618 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-14 06:29:54,618 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:54,898 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:54,898 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:54,898 DEBUG --- stdout --- 2024-11-14 06:29:54,898 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:54,898 DEBUG --- stderr --- 2024-11-14 06:29:54,898 DEBUG 2024-11-14 06:29:54,898 INFO 2024-11-14 06:29:54,898 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-90/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/stderr.txt 2024-11-14 06:29:54,898 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-14 06:29:55,658 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:55,658 DEBUG --- stdout --- 2024-11-14 06:29:55,658 DEBUG tar: Removing leading `/' from member names 2024-11-14 06:29:55,658 DEBUG --- stderr --- 2024-11-14 06:29:55,658 DEBUG 2024-11-14 06:29:55,659 INFO 2024-11-14 06:29:55,659 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-14 06:29:55,659 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:55,933 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:55,933 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:55,933 DEBUG --- stdout --- 2024-11-14 06:29:55,933 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:55,933 DEBUG --- stderr --- 2024-11-14 06:29:55,933 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-14 06:29:55,933 INFO 2024-11-14 06:29:55,933 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-14 06:29:55,933 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:56,212 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:56,212 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:56,212 DEBUG --- stdout --- 2024-11-14 06:29:56,212 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:56,212 DEBUG --- stderr --- 2024-11-14 06:29:56,212 DEBUG 2024-11-14 06:29:56,213 INFO 2024-11-14 06:29:56,213 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-90/stdout.txt" ]; then echo "/results/orders/benchmark-90/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-90/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-90/stdout.txt file found" 2024-11-14 06:29:56,213 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-14 06:29:57,008 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-14 06:29:57,008 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:57,008 DEBUG --- stdout --- 2024-11-14 06:29:57,008 DEBUG /results/orders/benchmark-90/stdout.txt file found 2024-11-14 06:29:57,008 DEBUG --- stderr --- 2024-11-14 06:29:57,008 DEBUG 2024-11-14 06:29:57,009 INFO 2024-11-14 06:29:57,009 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-14 06:29:57,009 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:57,286 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:57,286 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:57,286 DEBUG --- stdout --- 2024-11-14 06:29:57,286 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:57,287 DEBUG --- stderr --- 2024-11-14 06:29:57,287 DEBUG 2024-11-14 06:29:57,287 INFO 2024-11-14 06:29:57,287 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-90/stdout.txt 2024-11-14 06:29:58,078 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-14 06:29:58,078 DEBUG --- stdout --- 2024-11-14 06:29:58,078 DEBUG 6660 /results/orders/benchmark-90/stdout.txt 2024-11-14 06:29:58,078 DEBUG --- stderr --- 2024-11-14 06:29:58,078 DEBUG 2024-11-14 06:29:58,078 INFO - Download file (size 6660k) 2024-11-14 06:29:58,079 INFO 2024-11-14 06:29:58,079 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-14 06:29:58,079 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:58,355 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:58,355 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:58,355 DEBUG --- stdout --- 2024-11-14 06:29:58,355 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:58,355 DEBUG --- stderr --- 2024-11-14 06:29:58,355 DEBUG source : /results/orders/benchmark-90/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/stdout.txt 2024-11-14 06:29:58,355 INFO 2024-11-14 06:29:58,355 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-14 06:29:58,355 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:29:58,634 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:29:58,634 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:58,634 DEBUG --- stdout --- 2024-11-14 06:29:58,634 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:29:58,634 DEBUG --- stderr --- 2024-11-14 06:29:58,634 DEBUG 2024-11-14 06:29:58,634 INFO 2024-11-14 06:29:58,634 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-90/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/stdout.txt 2024-11-14 06:29:58,634 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-14 06:29:59,930 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:29:59,930 DEBUG --- stdout --- 2024-11-14 06:29:59,931 DEBUG tar: Removing leading `/' from member names 2024-11-14 06:29:59,931 DEBUG --- stderr --- 2024-11-14 06:29:59,931 DEBUG ________________________________________________________________________________ [2024-11-14 06:29:59] benchmark-90 show_cmd : Display order command ________________________________________________________________________________ 2024-11-14 06:29:59,931 INFO 2024-11-14 06:29:59,931 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-14 06:29:59,931 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:30:00,209 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:30:00,209 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:30:00,209 DEBUG --- stdout --- 2024-11-14 06:30:00,209 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:30:00,209 DEBUG --- stderr --- 2024-11-14 06:30:00,209 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-14 06:30:00,209 INFO 2024-11-14 06:30:00,209 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-14 06:30:00,209 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:30:00,486 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:30:00,486 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:30:00,486 DEBUG --- stdout --- 2024-11-14 06:30:00,486 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:30:00,486 DEBUG --- stderr --- 2024-11-14 06:30:00,486 DEBUG 2024-11-14 06:30:00,487 INFO 2024-11-14 06:30:00,487 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-90/cmd.txt" ]; then echo "/results/orders/benchmark-90/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-90/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-90/cmd.txt file found" 2024-11-14 06:30:00,487 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-14 06:30:01,283 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-14 06:30:01,283 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:30:01,283 DEBUG --- stdout --- 2024-11-14 06:30:01,283 DEBUG /results/orders/benchmark-90/cmd.txt file found 2024-11-14 06:30:01,283 DEBUG --- stderr --- 2024-11-14 06:30:01,283 DEBUG 2024-11-14 06:30:01,284 INFO 2024-11-14 06:30:01,284 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-14 06:30:01,284 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:30:01,559 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:30:01,559 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:30:01,559 DEBUG --- stdout --- 2024-11-14 06:30:01,560 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:30:01,560 DEBUG --- stderr --- 2024-11-14 06:30:01,560 DEBUG 2024-11-14 06:30:01,560 INFO 2024-11-14 06:30:01,560 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-90/cmd.txt 2024-11-14 06:30:02,356 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-14 06:30:02,356 DEBUG --- stdout --- 2024-11-14 06:30:02,356 DEBUG 4 /results/orders/benchmark-90/cmd.txt 2024-11-14 06:30:02,356 DEBUG --- stderr --- 2024-11-14 06:30:02,356 DEBUG 2024-11-14 06:30:02,356 INFO - Download file (size 4k) 2024-11-14 06:30:02,357 INFO 2024-11-14 06:30:02,357 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-14 06:30:02,357 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:30:02,633 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:30:02,633 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:30:02,633 DEBUG --- stdout --- 2024-11-14 06:30:02,633 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:30:02,633 DEBUG --- stderr --- 2024-11-14 06:30:02,633 DEBUG source : /results/orders/benchmark-90/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/cmd.txt 2024-11-14 06:30:02,634 INFO 2024-11-14 06:30:02,634 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-14 06:30:02,634 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-14 06:30:02,910 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-14 06:30:02,910 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:30:02,910 DEBUG --- stdout --- 2024-11-14 06:30:02,910 DEBUG overseer-0-6644bb6697-rchxr 2024-11-14 06:30:02,910 DEBUG --- stderr --- 2024-11-14 06:30:02,910 DEBUG 2024-11-14 06:30:02,910 INFO 2024-11-14 06:30:02,910 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-90/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/cmd.txt 2024-11-14 06:30:02,910 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-14 06:30:03,666 INFO [loop_until]: OK (rc = 0) 2024-11-14 06:30:03,666 DEBUG --- stdout --- 2024-11-14 06:30:03,666 DEBUG tar: Removing leading `/' from member names 2024-11-14 06:30:03,666 DEBUG --- stderr --- 2024-11-14 06:30:03,666 DEBUG 2024-11-14 06:30:03,666 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/cmd.txt is small (1 lines), printing all lines: 2024-11-14 06:30:03,666 DEBUG ----- output ----- 2024-11-14 06:30:03,666 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-90" --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=self90- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=90 -Duser_password=Pa_ssw0rd" 2024-11-14 06:30:03,666 INFO ________________________________________________________________________________ [2024-11-14 06:30:03] benchmark-90 show_rc : Display rc order ________________________________________________________________________________ 2024-11-14 06:30:03,666 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/rc.txt is small (1 lines), printing all lines: 2024-11-14 06:30:03,666 DEBUG ----- output ----- 2024-11-14 06:30:03,666 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-14 06:30:03,666 INFO ________________________________________________________________________________ [2024-11-14 06:30:03] benchmark-90 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-14 06:30:03,675 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/05_benchmark-90/stdout.txt is 73711 lines): 2024-11-14 06:30:03,675 DEBUG ----- output ----- 2024-11-14 06:30:03,675 INFO 2024-11-14 05:29:26,225 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp1_yfcs72 2024-11-14 06:30:03,675 INFO 2024-11-14 05:29:26,227 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-90" -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=self90- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=90 -Duser_password=**** 2024-11-14 06:30:03,675 INFO [INFO] Scanning for projects... 2024-11-14 06:30:03,675 INFO [INFO] 2024-11-14 06:30:03,675 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-14 06:30:03,675 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-14 06:30:03,675 INFO [INFO] from pom.xml 2024-11-14 06:30:03,675 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-14 06:30:03,675 INFO [INFO] 2024-11-14 06:30:03,675 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-14 06:30:03,675 INFO [INFO] 2024-11-14 06:30:03,675 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-14 06:30:03,675 INFO [INFO] skip non existing resourceDirectory /tmp/tmp1_yfcs72/src/main/resources 2024-11-14 06:30:03,675 INFO [INFO] 2024-11-14 06:30:03,675 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-14 06:30:03,675 INFO [INFO] No sources to compile 2024-11-14 06:30:03,675 INFO [INFO] 2024-11-14 06:30:03,675 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-14 06:30:03,675 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-14 06:30:03,675 INFO [INFO] 2024-11-14 06:30:03,675 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-14 06:30:03,675 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-14 06:30:03,675 INFO [INFO] 2024-11-14 06:30:03,675 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-14 06:30:03,675 INFO [INFO] compiling 38 Scala sources to /tmp/tmp1_yfcs72/target/test-classes ... 2024-11-14 06:30:03,675 INFO [INFO] compile in 6.8 s 2024-11-14 06:30:03,675 INFO [INFO] 2024-11-14 06:30:03,675 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-14 06:30:03,675 INFO [INFO] 2024-11-14 06:30:03,675 INFO [INFO] 2024-11-14 06:30:03,675 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-14 06:30:03,675 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-14 06:30:03,675 INFO Gatling 3.13.1 is available! (you're using 3.12.0) 2024-11-14 06:30:03,675 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-14 06:30:03,675 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-14 06:30:03,675 INFO 2024-11-14 06:30:03,675 INFO ================================================================================ 2024-11-14 06:30:03,675 INFO 2024-11-14 05:29:42 GMT 5s elapsed 2024-11-14 06:30:03,675 INFO ---- Requests ------------------------------------------------------------------ 2024-11-14 06:30:03,675 INFO > Global (OK=370 KO=0 ) 2024-11-14 06:30:03,675 INFO > restLoginInitiate (OK=34 KO=0 ) 2024-11-14 06:30:03,675 INFO > restLoginUsernamePasswordCallback (OK=33 KO=0 ) 2024-11-14 06:30:03,675 INFO > Skip 2FA (OK=22 KO=0 ) 2024-11-14 06:30:03,675 INFO > User Authorize PKCE stage (OK=36 KO=0 ) 2024-11-14 06:30:03,675 INFO > User AccessToken PKCE stage (OK=36 KO=0 ) 2024-11-14 06:30:03,676 INFO > GetUUid (OK=10 KO=0 ) 2024-11-14 06:30:03,676 INFO > GetOpenidConfig (OK=9 KO=0 ) 2024-11-14 06:30:03,676 INFO > CreatePolicy (OK=9 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI-Post-Login - stage 1 (OK=7 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=7 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI AM idFromSession (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI AM validateGoto (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI-Post-Login - stage 2 (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI Enduser appAuthHelper1 (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI Enduser appAuthHelper2 (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI-Post-Login - stage 3 (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI Enduser sessioncheck (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI IDM uiconfig (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI IDM version (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI IDM login (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI IDM privilege (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI IDM features (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI IDM dashboard (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI IDM user (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI IDM managed user schema (OK=6 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI IDM oidcToken (OK=4 KO=0 ) 2024-11-14 06:30:03,676 INFO > userinfo (OK=4 KO=0 ) 2024-11-14 06:30:03,676 INFO > loginTreeWorkflow_restLoginInitiate (OK=3 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI-Logout (OK=4 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI-Logout Redirect 1 (OK=4 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI AM endsession (OK=4 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI AM revoke1 (OK=4 KO=0 ) 2024-11-14 06:30:03,676 INFO > UI AM revoke2 (OK=4 KO=0 ) 2024-11-14 06:30:03,676 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=3 KO=0 ) 2024-11-14 06:30:03,676 INFO > getIdToken (OK=3 KO=0 ) 2024-11-14 06:30:03,676 INFO > getIdToken Redirect 1 (OK=3 KO=0 ) 2024-11-14 06:30:03,676 INFO > createOrganization (OK=3 KO=0 ) 2024-11-14 06:30:03,676 INFO > queryOrganization (OK=3 KO=0 ) 2024-11-14 06:30:03,676 INFO > deleteOrganization (OK=3 KO=0 ) 2024-11-14 06:30:03,676 INFO > tokenUserinfo (OK=2 KO=0 ) 2024-11-14 06:30:03,676 INFO > RootUserinfo (OK=13 KO=0 ) 2024-11-14 06:30:03,676 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-11-14 06:30:03,676 INFO 2024-11-14 06:30:03,676 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-14 06:30:03,676 INFO [- ] 0% 2024-11-14 06:30:03,676 INFO waiting: 326455 / active: 25 / done: 12 2024-11-14 06:30:03,676 INFO ================================================================================ 2024-11-14 06:30:03,676 INFO 2024-11-14 06:30:03,676 INFO 05:29:45.171 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-14 06:30:03,676 INFO 05:29:45.719 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-14 06:30:03,676 INFO 05:29:45.898 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-14 06:30:03,676 INFO 05:29:46.385 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-14 06:30:03,676 INFO 2024-11-14 06:30:03,676 INFO ================================================================================ 2024-11-14 06:30:03,676 INFO 2024-11-14 05:29:47 GMT 10s elapsed 2024-11-14 06:30:03,676 INFO ---- Requests ------------------------------------------------------------------ 2024-11-14 06:30:03,676 INFO > Global (OK=1694 KO=0 ) 2024-11-14 06:30:03,676 INFO > restLoginInitiate (OK=140 KO=0 ) 2024-11-14 06:30:03,676 INFO [...] 2024-11-14 06:30:03,687 INFO HTTP request: 2024-11-14 06:30:03,687 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/features?_queryFilter=true 2024-11-14 06:30:03,687 INFO headers: 2024-11-14 06:30:03,687 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-14 06:30:03,687 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-14 06:30:03,687 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI4NDg1ODcwNi0yNzhjLTRiMWQtYTBmNS01YTNhZjM4NjBmNDYiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMDdmNTQxOWYtOTZkNS00ZDM3LWJmOTgtYzU4ODYxMzA3NjhmLTk5MTY0MDg0Iiwic3VibmFtZSI6Ijg0ODU4NzA2LTI3OGMtNGIxZC1hMGY1LTVhM2FmMzg2MGY0NiIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IjdaVm5GODZfbVpKUER6WmppY3JSVUVYZmxjVSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTU2NTc3NiwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTU2NTc3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE1NjkzNzYsImlhdCI6MTczMTU2NTc3NiwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiX2pxSGJRcUFIaVZGdEJVYVZSU1hyaW1ONy1VIn0.nNe_jtTmSRA43tSAkJz9-vKxWTmm6k-rgHhMv-0s0-Y 2024-11-14 06:30:03,687 INFO Accept-Api-Version: 2024-11-14 06:30:03,687 INFO accept: */* 2024-11-14 06:30:03,687 INFO cookie: amlbcookie=01; 9b75a55744995d0=wmoP06EtOUImqu9lfTUbOTOq6a4.*AAJTSQACMDIAAlNLABxSam9BckpBTTA3R3plWGhmT3Z4UkhyaURwQTA9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-14 06:30:03,687 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-14 06:30:03,687 INFO cookies: 2024-11-14 06:30:03,687 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-14 06:30:03,687 INFO 9b75a55744995d0=wmoP06EtOUImqu9lfTUbOTOq6a4.*AAJTSQACMDIAAlNLABxSam9BckpBTTA3R3plWGhmT3Z4UkhyaURwQTA9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-14 06:30:03,688 INFO ========================= 2024-11-14 06:30:03,688 INFO HTTP response: 2024-11-14 06:30:03,688 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-14 06:30:03,688 INFO 06:29:37.077 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 323712: j.i.IOException: Premature close 2024-11-14 06:30:03,688 INFO 06:29:37.077 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-14 06:30:03,688 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-14 06:30:03,688 INFO Request: 2024-11-14 06:30:03,688 INFO UI IDM login: KO j.i.IOException: Premature close 2024-11-14 06:30:03,688 INFO ========================= 2024-11-14 06:30:03,688 INFO Session: 2024-11-14 06:30:03,688 INFO Session(idc.benchmark.Mix2024,323712,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkZWJjNTRjOS1jNDhmLTQ2MzYtYTQ0Yi1jYTEwNjIwMjQ2MzQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMGIzOGU2MjYtMDhkZC00YzdhLTliN2ItMGQxMTUzYWM3NjdkLTk4MzYwNDg5Iiwic3VibmFtZSI6ImRlYmM1NGM5LWM0OGYtNDYzNi1hNDRiLWNhMTA2MjAyNDYzNCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6ImtFMjh1Zm0wV2VDQU5iUWpXSXh2UGNvYllTdyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTU2NTc3NiwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTU2NTc3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE1NjkzNzYsImlhdCI6MTczMTU2NTc3NiwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiMjBIb09meUVWM2Ridl9TQWw5cXNtalR1VW1jIn0.NNxkmJ7oRMy5hFOFKmnoUalRmKvXzCJrxMjF34kv7cs, username -> user.26287, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkZWJjNTRjOS1jNDhmLTQ2MzYtYTQ0Yi1jYTEwNjIwMjQ2MzQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYTA2NGI3YzctNmJiYS00Y2MwLTg3ZTgtM2RhY2IxODliOTNkLTk4MzIwNDgxIiwic3VibmFtZSI6ImRlYmM1NGM5LWM0OGYtNDYzNi1hNDRiLWNhMTA2MjAyNDYzNCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IklGY0dCWlhSUERlb18tSEJlbWhQdzU4dzlJRSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTU2NTc3NiwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTU2NTc3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE1NjkzNzYsImlhdCI6MTczMTU2NTc3NiwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoic2ZteWZDREQ5LUxhTHV5Tk1VOEVpWlFVU284In0.-lQQhQuSeR9pydw9z_5icBdhv4_cdzu_zrPG9v1YVWs, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@2c802d74, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> QLOPnsblh_gdrzsRQr2-tZ6QX_Y, callbacks -> {"tokenId":"GefCzr1YP00MtRnUOQZclSMqpGU.*AAJTSQACMDIAAlNLABxGdTlGZ1pZU2dDQllDN2FCZXp6dE53QWdPZlk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731565776319), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=GefCzr1YP00MtRnUOQZclSMqpGU.*AAJTSQACMDIAAlNLABxGdTlGZ1pZU2dDQllDN2FCZXp6dE53QWdPZlk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731565776319))), user_uuid -> debc54c9-c48f-4636-a44b-ca1062024634, password -> Pa_ssw0rd, challenge -> b7OnsrIFA8_gwAl07-XDOVydJ84XF1E5WhSXjkhx4vQ, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 42.42.42.42, verifier -> LTE1MDkyNDU4ODA0MTc1MjU3OTQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@6cd22aa0, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@2697f72a, tokenId -> GefCzr1YP00MtRnUOQZclSMqpGU.*AAJTSQACMDIAAlNLABxGdTlGZ1pZU2dDQllDN2FCZXp6dE53QWdPZlk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, 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/0x00007a3dec69a0e0@1df061c1,io.netty.channel.epoll.EpollEventLoop@636e8cc) 2024-11-14 06:30:03,688 INFO ========================= 2024-11-14 06:30:03,688 INFO HTTP request: 2024-11-14 06:30:03,688 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-14 06:30:03,688 INFO headers: 2024-11-14 06:30:03,688 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-14 06:30:03,688 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-14 06:30:03,688 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkZWJjNTRjOS1jNDhmLTQ2MzYtYTQ0Yi1jYTEwNjIwMjQ2MzQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiYTA2NGI3YzctNmJiYS00Y2MwLTg3ZTgtM2RhY2IxODliOTNkLTk4MzIwNDgxIiwic3VibmFtZSI6ImRlYmM1NGM5LWM0OGYtNDYzNi1hNDRiLWNhMTA2MjAyNDYzNCIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IklGY0dCWlhSUERlb18tSEJlbWhQdzU4dzlJRSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTU2NTc3NiwiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTU2NTc3NiwicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE1NjkzNzYsImlhdCI6MTczMTU2NTc3NiwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoic2ZteWZDREQ5LUxhTHV5Tk1VOEVpWlFVU284In0.-lQQhQuSeR9pydw9z_5icBdhv4_cdzu_zrPG9v1YVWs 2024-11-14 06:30:03,688 INFO Accept-Api-Version: 2024-11-14 06:30:03,688 INFO accept: */* 2024-11-14 06:30:03,688 INFO cookie: amlbcookie=01; 9b75a55744995d0=GefCzr1YP00MtRnUOQZclSMqpGU.*AAJTSQACMDIAAlNLABxGdTlGZ1pZU2dDQllDN2FCZXp6dE53QWdPZlk9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-14 06:30:03,688 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-14 06:30:03,688 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-14 06:30:03,688 INFO content-length: 0 2024-11-14 06:30:03,688 INFO cookies: 2024-11-14 06:30:03,688 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-14 06:30:03,688 INFO 9b75a55744995d0=GefCzr1YP00MtRnUOQZclSMqpGU.*AAJTSQACMDIAAlNLABxGdTlGZ1pZU2dDQllDN2FCZXp6dE53QWdPZlk9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-14 06:30:03,688 INFO ========================= 2024-11-14 06:30:03,688 INFO HTTP response: 2024-11-14 06:30:03,688 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-14 06:30:03,688 INFO 06:29:37.077 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI AM validateGoto' failed for user 323737: j.i.IOException: Premature close 2024-11-14 06:30:03,688 INFO 06:29:37.077 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-14 06:30:03,688 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-14 06:30:03,688 INFO Request: 2024-11-14 06:30:03,688 INFO UI AM validateGoto: KO j.i.IOException: Premature close 2024-11-14 06:30:03,688 INFO ========================= 2024-11-14 06:30:03,688 INFO Session: 2024-11-14 06:30:03,688 INFO Session(idc.benchmark.Mix2024,323737,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, username -> user.54426, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@a385c4, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, callbacks -> {"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiI0YjIyMTAwYi02NDZhLTQ0N2QtYWY1MS1lZGRlOGMwNzNkMjMiLCJhdXRoSW5kZXhWYWx1ZSI6IkxvZ2luIiwib3RrIjoicTlvYWFsdjFrMmtkOHYwNTV1Z2lrOTVkMHEiLCJhdXRoSW5kZXhUeXBlIjoic2VydmljZSIsInJlYWxtIjoiL2FscGhhIiwic2Vzc2lvbklkIjoiKkFBSlRTUUFDTURJQUJIUjVjR1VBQ0VwWFZGOUJWVlJJQUFKVE1RQUNNREUuKmV5SjBlWEFpT2lKS1YxUWlMQ0pqZEhraU9pSktWMVFpTENKaGJHY2lPaUpJVXpJMU5pSjkuWlhsS01HVllRV2xQYVVwTFZqRlJhVXhEU214aWJVMXBUMmxLUWsxVVNUUlJNRXBFVEZWb1ZFMXFWVEpKYVhkcFdWZDRia2xxYjJsYVIyeDVTVzR3TGk1TlZGSkNVbFYyVFhWZmJURnlOVXhyWkdWMU5WZG5MazVCWmxwaldtOWZaa2xuWDNkT1UxTnVVVlZ6YW5GQ1FrTkxVSEp6Y0haNVUwVm9ja3R3Um1rd1NIZzNTSE5DU1ZnNVkwNWxlazVsTlVobll6WnJZMkYyUm14TFdtbG5hVWd5YlZabVZrUnRaRmMyTTA5d1ltRm5SakpFVkcxMFIwSkRlbVZzVG1OZmNrcFJSRlpoTWt4QmFHNDFMV2xvWVhSUE9GcG5OMFJ0YmtVMVNuSkJOVU5pU0dzeGVsZDBXWE5sUWxCWE4wcG9UWEprTms0eVF5MWtWa3RyV0hWQlFYTXlXbWxJT1Y5S2FYVTFORmxQV0VoeFFVbzBObWRMV25aTFF6ZEVUVVZpUzBWVFZrbFhTVEZqV25GRVVVTXdTM0J0YjFkUVkyWnJlSFZtVjBSUFdtNUVNbEZRTTNoRE1sSlJlVkY1VGw5VFRtTkpiMWgyU3pVMVZXeDNUamQwWm1zMFdtRkVWRGR1V0dkU01GUlFRbVJHVTBGMlpVZE1jRkZCT1dkNFdsQkplVGhyVFU5Q1VHRlRSRmt6UlcwemFWRjFNMlF0VEhWclVXcEVSak5oVDNGdlZVMVhVa0p5Y0ZadFdWVkxNVWd4VUVoM2R6QnNTSGs1Vmxvd1kxUnFZVWxGUW1aUE5YWk1lRkZMZVdwUVgwTm9ZeTF5UkZwVWRHWnpNbEZFY2pCb1dGZDZhblZPYVVkSVRUSkJaREZGZUVwcFZtVmZTWEJ5WlhNeFR6Uk1WbTkwVlZOTGVVTk9lRzFJZEdoR1VuSnhaM2w0VjB4ZmVVZE5VWFF3VVZaUk1VaDVabVY2TWpCR2FXMU1jblpEWjBkMFRDMVVZbHBqU21wMU5YTjJTVWhwVVdrMk4xZDRiVUpoVFZOTVYwbERUMDgxZDFoaVNHbG1URWR0YTJabFZUazJNbFpsYjFORmJXdHhVWFZxUjFWUkxURmlhRVF4TjJneU9YUjBVMlJvWVUwNWJYUk1NbmxCYkZCNlNWRTFMV2RTWkhsaVNIY3lMVUpFVjBFeFJ6SmZTbEp4UW1SeVVXb3pkbWsyUm1kc2VtVlNVa3g2UjBWM2NqVXhjM2MzYTFOQmFtaExZemhuWldKRVZHdHJSR0ptTm5OQ1gyaHdVRU0wZVRRM1luWTFiMGx3VTFwaVpUUXRSa3M1ZUhCR1lWRklYM2MzUVZadldtdHZZVkZoZUUxU1YxQnJNR1UzU21KRE0waHRWRlpVZFdaZmJVZ3lNbm81VG1kSk9URndjVzB6ZEhVNWNYQjZiMjQzUWtZeVlWbzBiVFl3V0RKb1F6TXdZMjlGVDNsSFNrWTBWalJLTjBOa1lreENSVm81TURabVYyaGtkelpQUWpoQk5sTTVRV1JMT0dOUmNETnRRMUp2VUhoNk1UTldWRWhLTm1scE5VeHhTMlpWVjAxWGIxUXpOWFpEU2taUmVsRnNNV1V3V213eVJDMTNWblY1WmxNeVVUSXdXWEp6TW01cVEyVTRWR1Z5ZW5Cd1FXaG1ZbDlWVm1kV2VsSjFSV2RUVjJSbFoySjRWbVJMYkhabFFURTBhekJ6Wm5VelRVTnhPRmd3T1ZSVE1uRk5XVWwwTVdoMmJIUjZVMlZJUTFnM2FrUTFhR3h3UldoTmNsbDBUamxpYzE5elRWVmZlV1U1VURWbk1HWlJabUprY2xWc2NtbzBXRVEwVmt4VU5UQnpUMWRTUW5ZNVNtc3hSMkZ0VGpSbVoxQm9ZelJpTVhWeFMyZHZiRVJ2UmpKWVlWSm9MVzFMVURoTU9GSlBRV1F0YkhCR2F6TnRTbFprYzNOQ1REQjFNMkk1VWsxQloxTXhUMUJVVEhObk9XSmtOVXBUYlc5WFpHSkdXRnA2TjJjd05HUXhYMlprVUZVM2JtUjBha3BGUTNGUFgyTkliR2RCT0haamRsVjBSamRIVlU5eE9GUmtNMmRJYURSbWJpMXRjMDlqVmsweE16VkxVVEowWmpac01XSTBhM2t3WXpCbFZXNW1TbWRDWkhKVWRXTjRXbEZFU1ZOWmF6WTJlWEpIYUU5dVZHNTJhR3h5VjI1M1FXbFFOamh1UVU4d1dVWm5kRmROYTNCcWJYWjJTVTVrWjNSVWJESnphVkI2UzJabVl6bFBWM0I0Ym5selducEJWVzFzUzFJdFRsZFplVmRTYW5WdE5XNWxTMnRMT1ZaemVubEdaV0phZG5RMWJVUnFUMUpFYUVoYVIwMTVhMTlyYlZwM1pGZGxPRFV0VDBwWlFtSjJlbTVTV0dReGFIWmxaR2xMV0VrNWNWRnlSMFJJYm5Sc05WRnJPWEJzT0hoVWJIQjVjWFJQUkZaaGNIZE5URGhwUXpCdlJtSXdRV2x5ZG1odVZrdExiRE5VTUdsemRWSnhialE0ZVdWV2RGQmFSMGhqZDI4MlgxZDFTRFZtYVU1YWVFVjFjbDlXT0hocFoxWkVjbGwzYlV4Nk5HNVFVMGRIVVVFeVZrazRWV2hRVUc5VlVUQXhZbXBxU1haVlJGRlJkMUJ5UzB4SlUwVk5SV1pJY0hGc2VEUktTR2RIVm5wcE1tMVFZbTlTZEZRNVkzaFdiMVJwTUVsc1NqRmFXVjkzZW5adlZqWjJlV0Z5WW1KQlNUUkdVSFJ3ZWtaaGEwWXlRbTlVTkU5bkxUWjFUa05PVm1GRFFuaHlkRlk1TkVadFoxbEZlRFF4VVZCZk1WOVRTSFZaUmsxZmFHazVaVFZIUlVZNWFEUmpRaTFyTjA5VGFHazRPWGRDWVRsUlpIVTFXRko1V2xFNE5FOVVWVEp3UW5sSFZVMUNSa0ZrZW1OcGFFdGpZVlpCTlhJeFlqRm9VeloxWDA5UU9HaDZaSE5mYjFCb2FGbEljVEpvWTBWNFprbExXbGxNUmxoTFZWcEVZbHB2YzNCdFRXWTRaR2M0ZVVKa1YyNWpPV2szTTFabmFqUnpZblZMTWxFeFNtOUpjMmhTUTNKQ1JsZ3RUMWQzWlRVM05XZEtNRTR3WjAwMFZUZDFWRGR5Vld4U2JWQnpOa3hTYVdKWFpFa3pUSEZpUm1kWGVVMW1ha1IwZG1OUmQzcERiMmxHVTBKUGIyNVNhamhoTUY4emJIazRPRkV5ZEZKdFRYbFlUMWh6T1ZFeU0xTlhia3N6Y2tSTloyVkVaV3QyUzFOUFkxaHpSMGwzYkhnNFYzRmpXRWxwVFRWdVF6bGFUbEIxTm1kMGRGOVlNM1ZSZUV0R1h6UmFVVlpQU1VaUExYVldSbmR5YkV0RmQzVjJRVkJETkZNNWFUWndUamRUV1hJNGJrRlBkR2haUkdkS2RUWkJOak0zUmt4R1ptaEpNREpYTVRKU1EyRllMVjl5YjI1YWVHTklRME5FUmpkdFNtRjROMk41VVZkb1NXbFRTMXBwVlRseGVsZEZNbTkxUW1OTWNrRkRXRzR4VmpKb1ZrSlBZV3hGTlRobVZGOWxVWE4xTTA4NFdWWm5UMkp0Y21GVWQxRk5YMFJsVW5Kbk0zbHNjbUZqYkZSamVVZHFZVzgwU0ZjeWMxa3RkbWhtVTNaa1drNW1jVE5SYUcxYVJuazFZM05yZGpKRWJIcGhjRmt5TjJFNU5tOUZORzlHUkdsalUya3hSSEpwVFVWU2JEQTRNRWM0Y0U1cU4wazJOV2xoUm5BdE1VVk5keTUzUlZJelZXcFhXVmc0V21sMmVWaG5aVkJtWXpCUi5Rd2M1NUY1QnRxYWFISWlhUUNYZnM4MW00X2xGaGkxU3N0akVXSEt6MDAwIiwiZXhwIjoxNzMxNTY2MDc2LCJpYXQiOjE3MzE1NjU3NzZ9.Tkb9AyWBLyT79vi3vMQI6dWt2IwLRFK65uSrQVJVFYk","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,1731565776927), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=o8TLXxxUFRM3ejQGrh7-j_x5ofo.*AAJTSQACMDIAAlNLABxtdm5FUDQvZmlrQlFFM25QTDlGQVNPWFBJdGs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*,true,false,1731565776927))), password -> Pa_ssw0rd, fake-ip -> 227.146.82.163, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@81edd2, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@1eee78fd, tokenId -> o8TLXxxUFRM3ejQGrh7-j_x5ofo.*AAJTSQACMDIAAlNLABxtdm5FUDQvZmlrQlFFM25QTDlGQVNPWFBJdGs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:60.0) Gecko/20100101 Firefox/60.0),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007a3dec69a0e0@1df061c1,io.netty.channel.epoll.EpollEventLoop@5b7ea70d) 2024-11-14 06:30:03,688 INFO ========================= 2024-11-14 06:30:03,688 INFO HTTP request: 2024-11-14 06:30:03,688 INFO POST https://openam-perf-benchmark.forgeblocks.com/am/json/realms/root/realms/alpha/users?_action=validateGoto 2024-11-14 06:30:03,688 INFO headers: 2024-11-14 06:30:03,688 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-14 06:30:03,688 INFO Accept-API-Version: resource=2.1, protocol=1.0 2024-11-14 06:30:03,688 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-14 06:30:03,688 INFO accept: */* 2024-11-14 06:30:03,688 INFO cookie: amlbcookie=01; 9b75a55744995d0=o8TLXxxUFRM3ejQGrh7-j_x5ofo.*AAJTSQACMDIAAlNLABxtdm5FUDQvZmlrQlFFM25QTDlGQVNPWFBJdGs9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-14 06:30:03,688 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-14 06:30:03,688 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-14 06:30:03,688 INFO content-length: 0 2024-11-14 06:30:03,688 INFO cookies: 2024-11-14 06:30:03,688 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-14 06:30:03,688 INFO 9b75a55744995d0=o8TLXxxUFRM3ejQGrh7-j_x5ofo.*AAJTSQACMDIAAlNLABxtdm5FUDQvZmlrQlFFM25QTDlGQVNPWFBJdGs9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-14 06:30:03,688 INFO ========================= 2024-11-14 06:30:03,688 INFO HTTP response: 2024-11-14 06:30:03,688 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-14 06:30:03,688 INFO 06:29:37.077 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'restLoginInitiate' failed for user 323808: j.i.IOException: Premature close 2024-11-14 06:30:03,688 INFO 06:29:37.077 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-14 06:30:03,688 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-14 06:30:03,688 INFO Request: 2024-11-14 06:30:03,688 INFO restLoginInitiate: KO j.i.IOException: Premature close 2024-11-14 06:30:03,689 INFO ========================= 2024-11-14 06:30:03,689 INFO Session: 2024-11-14 06:30:03,689 INFO Session(idc.benchmark.Mix2024,323808,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, username -> user.190246, randomPolicyName -> testPolicy707026, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@69d39835, 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, password -> Pa_ssw0rd, fake-ip -> 237.83.233.85, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@4415e3f6),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007a3dec69a0e0@1df061c1,io.netty.channel.epoll.EpollEventLoop@636e8cc) 2024-11-14 06:30:03,689 INFO ========================= 2024-11-14 06:30:03,689 INFO HTTP request: 2024-11-14 06:30:03,689 INFO POST https://openam-perf-benchmark.forgeblocks.com/am/json/realms/root/authenticate 2024-11-14 06:30:03,689 INFO headers: 2024-11-14 06:30:03,689 INFO accept: application/json 2024-11-14 06:30:03,689 INFO content-type: application/json 2024-11-14 06:30:03,689 INFO 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 2024-11-14 06:30:03,689 INFO X-Forwarded-For: 237.83.233.85 2024-11-14 06:30:03,689 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-14 06:30:03,689 INFO content-length: 0 2024-11-14 06:30:03,689 INFO body:StringChunksRequestBody{charset=UTF-8, content=} 2024-11-14 06:30:03,689 INFO ========================= 2024-11-14 06:30:03,689 INFO HTTP response: 2024-11-14 06:30:03,689 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-14 06:30:03,689 INFO 06:29:37.077 [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-14 06:30:03,689 INFO [INFO] ------------------------------------------------------------------------ 2024-11-14 06:30:03,689 INFO [INFO] BUILD SUCCESS 2024-11-14 06:30:03,689 INFO [INFO] ------------------------------------------------------------------------ 2024-11-14 06:30:03,689 INFO [INFO] Total time: 01:00 h 2024-11-14 06:30:03,689 INFO [INFO] Finished at: 2024-11-14T06:29:37Z 2024-11-14 06:30:03,689 INFO [INFO] ------------------------------------------------------------------------ 2024-11-14 06:30:03,689 INFO 2024-11-14 06:29:37,196 INFO Gatling simulation run ended successfully 2024-11-14 06:30:03,689 INFO 2024-11-14 06:29:37,196 DEBUG No archive name provided. No archive will be created 2024-11-14 06:30:03,689 INFO ________________________________________________________________________________ [2024-11-14 06:30:03] benchmark-90 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-14 06:30:03,689 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/05_benchmark-90/stderr.txt is small (0 lines), printing all lines: 2024-11-14 06:30:03,689 DEBUG ----- output ----- 2024-11-14 06:30:03,689 DEBUG 2024-11-14 06:30:03,689 INFO ________________________________________________________________________________ [2024-11-14 06:30:03] benchmark-90 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-14 06:30:03] benchmark-90 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-14 06:30:03] benchmark-90 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped