--Task-- name: benchmark-160 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-150'] preceding_task: benchmark-150 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self1r160-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 160} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-18 22:23:45] benchmark-160 pre : N/A ________________________________________________________________________________ 2024-11-18 22:23:45 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-18 22:23:45 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-160/stdout.txt Pod log : /results/orders/benchmark-160 2024-11-18 22:23:45,757 INFO 2024-11-18 22:23:45,757 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 22:23:45,757 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:23:46,047 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:23:46,047 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:23:46,047 DEBUG --- stdout --- 2024-11-18 22:23:46,047 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:23:46,047 DEBUG --- stderr --- 2024-11-18 22:23:46,047 DEBUG ________________________________________________________________________________ [2024-11-18 22:23:46] benchmark-160 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-18 22:23:46,048 INFO 2024-11-18 22:23:46,048 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 22:23:46,048 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:23:46,320 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:23:46,320 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:23:46,320 DEBUG --- stdout --- 2024-11-18 22:23:46,320 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:23:46,320 DEBUG --- stderr --- 2024-11-18 22:23:46,320 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-160" --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=self1r160- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=160 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-18 22:23:46] benchmark-160 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-18 22:23:46,321 INFO 2024-11-18 22:23:46,321 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 22:23:46,321 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:23:46,592 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:23:46,592 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:23:46,592 DEBUG --- stdout --- 2024-11-18 22:23:46,592 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:23:46,592 DEBUG --- stderr --- 2024-11-18 22:23:46,592 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/order.json { "name": "benchmark-160", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-160\" --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=self1r160- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=160 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-160" } ________________________________________________________________________________ [2024-11-18 22:23:46] benchmark-160 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 22:23:46,595 INFO 2024-11-18 22:23:46,595 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-18 22:23:46,595 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 22:23:46,849 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 22:23:46,849 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:23:46,849 DEBUG --- stdout --- 2024-11-18 22:23:46,849 DEBUG WAITING 2024-11-18 22:23:46,849 DEBUG --- stderr --- 2024-11-18 22:23:46,849 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 28 0 --:--:-- --:--:-- --:--:-- 28 ________________________________________________________________________________ [2024-11-18 22:23:46] benchmark-160 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-18 22:23:46,850 INFO 2024-11-18 22:23:46,850 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 22:23:46,850 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:23:47,119 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:23:47,119 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:23:47,119 DEBUG --- stdout --- 2024-11-18 22:23:47,119 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:23:47,119 DEBUG --- stderr --- 2024-11-18 22:23:47,119 DEBUG 2024-11-18 22:23:47,120 INFO 2024-11-18 22:23:47,120 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/07_benchmark-160/order.json overseer-0-6644bb6697-rchxr:/results/orders/order.json 2024-11-18 22:23:47,120 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 22:23:48,487 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:23:48,487 DEBUG --- stdout --- 2024-11-18 22:23:48,487 DEBUG 2024-11-18 22:23:48,487 DEBUG --- stderr --- 2024-11-18 22:23:48,487 DEBUG Order has been successfully forwarded to overseer 2024-11-18 22:23:48 - INFO: Check if Gatling simulation started successfully 2024-11-18 22:23:48,487 INFO 2024-11-18 22:23:48,487 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 22:23:48,487 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:23:48,757 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:23:48,757 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:23:48,757 DEBUG --- stdout --- 2024-11-18 22:23:48,757 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:23:48,757 DEBUG --- stderr --- 2024-11-18 22:23:48,757 DEBUG 2024-11-18 22:23:48,757 INFO 2024-11-18 22:23:48,757 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-160/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-18 22:23:48,757 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 22:23:49,478 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 22:23:55,205 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 22:24:00,915 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-18 22:24:00,915 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:24:00,915 DEBUG --- stdout --- 2024-11-18 22:24:00,915 DEBUG 2024-11-18 22:23:49,322 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmppsl48hgw 2024-11-18 22:23:49,324 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-160" -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=self1r160- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=160 -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/tmppsl48hgw/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/tmppsl48hgw/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-18 22:24:00,915 DEBUG --- stderr --- 2024-11-18 22:24:00,915 DEBUG ________________________________________________________________________________ [2024-11-18 22:24:00] benchmark-160 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 05s, 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 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 23:24:09] benchmark-160 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-18 23:24:09,807 INFO 2024-11-18 23:24:09,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 23:24:09,807 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:10,108 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:10,108 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:10,108 DEBUG --- stdout --- 2024-11-18 23:24:10,108 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:10,108 DEBUG --- stderr --- 2024-11-18 23:24:10,108 DEBUG 2024-11-18 23:24:10,108 INFO 2024-11-18 23:24:10,108 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 23:24:10,108 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 23:24:10,868 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:10,868 DEBUG --- stdout --- 2024-11-18 23:24:10,868 DEBUG 2024-11-18 23:24:10,868 DEBUG --- stderr --- 2024-11-18 23:24:10,868 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-18 23:24:10] benchmark-160 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-18 23:24:10 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/order.json 2024-11-18 23:24:10,869 INFO 2024-11-18 23:24:10,869 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 23:24:10,869 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:11,141 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:11,141 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:11,141 DEBUG --- stdout --- 2024-11-18 23:24:11,141 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:11,141 DEBUG --- stderr --- 2024-11-18 23:24:11,142 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 23:24:11,142 INFO 2024-11-18 23:24:11,142 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 23:24:11,142 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:11,414 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:11,414 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:11,414 DEBUG --- stdout --- 2024-11-18 23:24:11,414 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:11,414 DEBUG --- stderr --- 2024-11-18 23:24:11,414 DEBUG 2024-11-18 23:24:11,415 INFO 2024-11-18 23:24:11,415 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-160/rc.txt" ]; then echo "/results/orders/benchmark-160/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-160/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-160/rc.txt file found" 2024-11-18 23:24:11,415 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 23:24:12,197 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 23:24:12,197 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:12,197 DEBUG --- stdout --- 2024-11-18 23:24:12,197 DEBUG /results/orders/benchmark-160/rc.txt file found 2024-11-18 23:24:12,197 DEBUG --- stderr --- 2024-11-18 23:24:12,197 DEBUG 2024-11-18 23:24:12,197 INFO 2024-11-18 23:24:12,197 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 23:24:12,197 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:12,492 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:12,492 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:12,492 DEBUG --- stdout --- 2024-11-18 23:24:12,492 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:12,492 DEBUG --- stderr --- 2024-11-18 23:24:12,492 DEBUG 2024-11-18 23:24:12,492 INFO 2024-11-18 23:24:12,492 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-160/rc.txt 2024-11-18 23:24:13,270 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 23:24:13,270 DEBUG --- stdout --- 2024-11-18 23:24:13,270 DEBUG 4 /results/orders/benchmark-160/rc.txt 2024-11-18 23:24:13,270 DEBUG --- stderr --- 2024-11-18 23:24:13,270 DEBUG 2024-11-18 23:24:13,270 INFO - Download file (size 4k) 2024-11-18 23:24:13,270 INFO 2024-11-18 23:24:13,270 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 23:24:13,270 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:13,544 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:13,545 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:13,545 DEBUG --- stdout --- 2024-11-18 23:24:13,545 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:13,545 DEBUG --- stderr --- 2024-11-18 23:24:13,545 DEBUG source : /results/orders/benchmark-160/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/rc.txt 2024-11-18 23:24:13,545 INFO 2024-11-18 23:24:13,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-18 23:24:13,545 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:13,817 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:13,817 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:13,817 DEBUG --- stdout --- 2024-11-18 23:24:13,817 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:13,817 DEBUG --- stderr --- 2024-11-18 23:24:13,817 DEBUG 2024-11-18 23:24:13,817 INFO 2024-11-18 23:24:13,817 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-160/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/rc.txt 2024-11-18 23:24:13,817 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 23:24:14,561 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:14,561 DEBUG --- stdout --- 2024-11-18 23:24:14,561 DEBUG tar: Removing leading `/' from member names 2024-11-18 23:24:14,561 DEBUG --- stderr --- 2024-11-18 23:24:14,561 DEBUG 2024-11-18 23:24:14,561 INFO 2024-11-18 23:24:14,561 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 23:24:14,561 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:14,835 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:14,835 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:14,835 DEBUG --- stdout --- 2024-11-18 23:24:14,835 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:14,835 DEBUG --- stderr --- 2024-11-18 23:24:14,835 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 23:24:14,836 INFO 2024-11-18 23:24:14,836 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 23:24:14,836 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:15,107 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:15,107 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:15,107 DEBUG --- stdout --- 2024-11-18 23:24:15,107 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:15,107 DEBUG --- stderr --- 2024-11-18 23:24:15,107 DEBUG 2024-11-18 23:24:15,108 INFO 2024-11-18 23:24:15,108 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-160/stderr.txt" ]; then echo "/results/orders/benchmark-160/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-160/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-160/stderr.txt file found" 2024-11-18 23:24:15,108 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 23:24:15,889 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 23:24:15,889 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:15,889 DEBUG --- stdout --- 2024-11-18 23:24:15,889 DEBUG /results/orders/benchmark-160/stderr.txt file found 2024-11-18 23:24:15,889 DEBUG --- stderr --- 2024-11-18 23:24:15,889 DEBUG 2024-11-18 23:24:15,889 INFO 2024-11-18 23:24:15,889 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 23:24:15,889 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:16,161 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:16,161 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:16,161 DEBUG --- stdout --- 2024-11-18 23:24:16,161 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:16,161 DEBUG --- stderr --- 2024-11-18 23:24:16,161 DEBUG 2024-11-18 23:24:16,161 INFO 2024-11-18 23:24:16,161 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-160/stderr.txt 2024-11-18 23:24:16,939 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 23:24:16,939 DEBUG --- stdout --- 2024-11-18 23:24:16,939 DEBUG 0 /results/orders/benchmark-160/stderr.txt 2024-11-18 23:24:16,939 DEBUG --- stderr --- 2024-11-18 23:24:16,939 DEBUG 2024-11-18 23:24:16,939 INFO - Download file (size 0k) 2024-11-18 23:24:16,939 INFO 2024-11-18 23:24:16,939 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 23:24:16,939 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:17,211 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:17,211 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:17,211 DEBUG --- stdout --- 2024-11-18 23:24:17,211 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:17,211 DEBUG --- stderr --- 2024-11-18 23:24:17,211 DEBUG source : /results/orders/benchmark-160/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/stderr.txt 2024-11-18 23:24:17,211 INFO 2024-11-18 23:24:17,211 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 23:24:17,211 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:17,483 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:17,483 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:17,483 DEBUG --- stdout --- 2024-11-18 23:24:17,483 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:17,483 DEBUG --- stderr --- 2024-11-18 23:24:17,483 DEBUG 2024-11-18 23:24:17,483 INFO 2024-11-18 23:24:17,483 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-160/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/stderr.txt 2024-11-18 23:24:17,483 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 23:24:18,229 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:18,229 DEBUG --- stdout --- 2024-11-18 23:24:18,229 DEBUG tar: Removing leading `/' from member names 2024-11-18 23:24:18,229 DEBUG --- stderr --- 2024-11-18 23:24:18,229 DEBUG 2024-11-18 23:24:18,229 INFO 2024-11-18 23:24:18,229 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 23:24:18,229 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:18,500 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:18,500 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:18,500 DEBUG --- stdout --- 2024-11-18 23:24:18,501 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:18,501 DEBUG --- stderr --- 2024-11-18 23:24:18,501 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 23:24:18,501 INFO 2024-11-18 23:24:18,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 23:24:18,501 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:18,772 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:18,772 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:18,772 DEBUG --- stdout --- 2024-11-18 23:24:18,772 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:18,772 DEBUG --- stderr --- 2024-11-18 23:24:18,772 DEBUG 2024-11-18 23:24:18,774 INFO 2024-11-18 23:24:18,774 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-160/stdout.txt" ]; then echo "/results/orders/benchmark-160/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-160/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-160/stdout.txt file found" 2024-11-18 23:24:18,774 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 23:24:19,580 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 23:24:19,580 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:19,580 DEBUG --- stdout --- 2024-11-18 23:24:19,580 DEBUG /results/orders/benchmark-160/stdout.txt file found 2024-11-18 23:24:19,580 DEBUG --- stderr --- 2024-11-18 23:24:19,580 DEBUG 2024-11-18 23:24:19,580 INFO 2024-11-18 23:24:19,580 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 23:24:19,580 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:19,854 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:19,854 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:19,854 DEBUG --- stdout --- 2024-11-18 23:24:19,854 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:19,854 DEBUG --- stderr --- 2024-11-18 23:24:19,854 DEBUG 2024-11-18 23:24:19,854 INFO 2024-11-18 23:24:19,854 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-160/stdout.txt 2024-11-18 23:24:20,638 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 23:24:20,638 DEBUG --- stdout --- 2024-11-18 23:24:20,638 DEBUG 1645864 /results/orders/benchmark-160/stdout.txt 2024-11-18 23:24:20,638 DEBUG --- stderr --- 2024-11-18 23:24:20,638 DEBUG 2024-11-18 23:24:20,638 INFO - Download file (size 1645864k) 2024-11-18 23:24:20,638 INFO 2024-11-18 23:24:20,638 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 23:24:20,638 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:20,910 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:20,911 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:20,911 DEBUG --- stdout --- 2024-11-18 23:24:20,911 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:20,911 DEBUG --- stderr --- 2024-11-18 23:24:20,911 DEBUG source : /results/orders/benchmark-160/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/stdout.txt 2024-11-18 23:24:20,911 INFO 2024-11-18 23:24:20,911 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 23:24:20,911 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:21,186 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:21,186 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:21,186 DEBUG --- stdout --- 2024-11-18 23:24:21,186 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:21,186 DEBUG --- stderr --- 2024-11-18 23:24:21,186 DEBUG 2024-11-18 23:24:21,187 INFO 2024-11-18 23:24:21,187 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-160/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/stdout.txt 2024-11-18 23:24:21,187 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 23:24:59,509 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:59,509 DEBUG --- stdout --- 2024-11-18 23:24:59,509 DEBUG tar: Removing leading `/' from member names 2024-11-18 23:24:59,509 DEBUG --- stderr --- 2024-11-18 23:24:59,509 DEBUG ________________________________________________________________________________ [2024-11-18 23:24:59] benchmark-160 show_cmd : Display order command ________________________________________________________________________________ 2024-11-18 23:24:59,509 INFO 2024-11-18 23:24:59,509 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 23:24:59,509 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:24:59,780 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:24:59,780 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:24:59,780 DEBUG --- stdout --- 2024-11-18 23:24:59,780 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:24:59,780 DEBUG --- stderr --- 2024-11-18 23:24:59,780 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 23:24:59,780 INFO 2024-11-18 23:24:59,780 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 23:24:59,780 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:25:00,052 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:25:00,052 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:25:00,052 DEBUG --- stdout --- 2024-11-18 23:25:00,052 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:25:00,052 DEBUG --- stderr --- 2024-11-18 23:25:00,052 DEBUG 2024-11-18 23:25:00,053 INFO 2024-11-18 23:25:00,053 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-160/cmd.txt" ]; then echo "/results/orders/benchmark-160/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-160/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-160/cmd.txt file found" 2024-11-18 23:25:00,053 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 23:25:00,838 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 23:25:00,838 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:25:00,838 DEBUG --- stdout --- 2024-11-18 23:25:00,838 DEBUG /results/orders/benchmark-160/cmd.txt file found 2024-11-18 23:25:00,838 DEBUG --- stderr --- 2024-11-18 23:25:00,838 DEBUG 2024-11-18 23:25:00,838 INFO 2024-11-18 23:25:00,838 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 23:25:00,838 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:25:01,111 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:25:01,111 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:25:01,111 DEBUG --- stdout --- 2024-11-18 23:25:01,111 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:25:01,111 DEBUG --- stderr --- 2024-11-18 23:25:01,111 DEBUG 2024-11-18 23:25:01,111 INFO 2024-11-18 23:25:01,112 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-160/cmd.txt 2024-11-18 23:25:01,902 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 23:25:01,902 DEBUG --- stdout --- 2024-11-18 23:25:01,902 DEBUG 4 /results/orders/benchmark-160/cmd.txt 2024-11-18 23:25:01,902 DEBUG --- stderr --- 2024-11-18 23:25:01,902 DEBUG 2024-11-18 23:25:01,902 INFO - Download file (size 4k) 2024-11-18 23:25:01,902 INFO 2024-11-18 23:25:01,902 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 23:25:01,902 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:25:02,178 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:25:02,178 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:25:02,178 DEBUG --- stdout --- 2024-11-18 23:25:02,178 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:25:02,178 DEBUG --- stderr --- 2024-11-18 23:25:02,178 DEBUG source : /results/orders/benchmark-160/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/cmd.txt 2024-11-18 23:25:02,179 INFO 2024-11-18 23:25:02,179 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 23:25:02,179 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 23:25:02,449 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 23:25:02,449 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:25:02,449 DEBUG --- stdout --- 2024-11-18 23:25:02,449 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 23:25:02,449 DEBUG --- stderr --- 2024-11-18 23:25:02,449 DEBUG 2024-11-18 23:25:02,450 INFO 2024-11-18 23:25:02,450 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-160/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/cmd.txt 2024-11-18 23:25:02,450 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 23:25:03,194 INFO [loop_until]: OK (rc = 0) 2024-11-18 23:25:03,194 DEBUG --- stdout --- 2024-11-18 23:25:03,194 DEBUG tar: Removing leading `/' from member names 2024-11-18 23:25:03,194 DEBUG --- stderr --- 2024-11-18 23:25:03,194 DEBUG 2024-11-18 23:25:03,195 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/cmd.txt is small (1 lines), printing all lines: 2024-11-18 23:25:03,195 DEBUG ----- output ----- 2024-11-18 23:25:03,195 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-160" --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=self1r160- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=160 -Duser_password=Pa_ssw0rd" 2024-11-18 23:25:03,195 INFO ________________________________________________________________________________ [2024-11-18 23:25:03] benchmark-160 show_rc : Display rc order ________________________________________________________________________________ 2024-11-18 23:25:03,195 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/rc.txt is small (1 lines), printing all lines: 2024-11-18 23:25:03,195 DEBUG ----- output ----- 2024-11-18 23:25:03,195 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-18 23:25:03,195 INFO ________________________________________________________________________________ [2024-11-18 23:25:03] benchmark-160 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-18 23:25:05,075 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/07_benchmark-160/stdout.txt is 14768094 lines): 2024-11-18 23:25:05,075 DEBUG ----- output ----- 2024-11-18 23:25:05,075 INFO 2024-11-18 22:23:49,322 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmppsl48hgw 2024-11-18 23:25:05,076 INFO 2024-11-18 22:23:49,324 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-160" -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=self1r160- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=160 -Duser_password=**** 2024-11-18 23:25:05,076 INFO [INFO] Scanning for projects... 2024-11-18 23:25:05,076 INFO [INFO] 2024-11-18 23:25:05,076 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-18 23:25:05,076 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-18 23:25:05,076 INFO [INFO] from pom.xml 2024-11-18 23:25:05,076 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-18 23:25:05,076 INFO [INFO] 2024-11-18 23:25:05,076 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-18 23:25:05,076 INFO [INFO] 2024-11-18 23:25:05,076 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-18 23:25:05,076 INFO [INFO] skip non existing resourceDirectory /tmp/tmppsl48hgw/src/main/resources 2024-11-18 23:25:05,076 INFO [INFO] 2024-11-18 23:25:05,076 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-18 23:25:05,076 INFO [INFO] No sources to compile 2024-11-18 23:25:05,076 INFO [INFO] 2024-11-18 23:25:05,076 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-18 23:25:05,076 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-18 23:25:05,076 INFO [INFO] 2024-11-18 23:25:05,076 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-18 23:25:05,076 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-18 23:25:05,076 INFO [INFO] 2024-11-18 23:25:05,076 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-18 23:25:05,076 INFO [INFO] compiling 38 Scala sources to /tmp/tmppsl48hgw/target/test-classes ... 2024-11-18 23:25:05,076 INFO [INFO] compile in 6.8 s 2024-11-18 23:25:05,076 INFO [INFO] 2024-11-18 23:25:05,076 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-18 23:25:05,076 INFO [INFO] 2024-11-18 23:25:05,076 INFO [INFO] 2024-11-18 23:25:05,076 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-18 23:25:05,076 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-18 23:25:05,076 INFO Gatling 3.13.1 is available! (you're using 3.12.0) 2024-11-18 23:25:05,076 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-18 23:25:05,076 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-18 23:25:05,076 INFO 22:24:01.338 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'restLoginUsernamePasswordCallback' failed for user 3: status.find.is(200), but actually found 401 2024-11-18 23:25:05,076 INFO 22:24:01.345 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 23:25:05,076 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 23:25:05,076 INFO Request: 2024-11-18 23:25:05,076 INFO restLoginUsernamePasswordCallback: KO status.find.is(200), but actually found 401 2024-11-18 23:25:05,076 INFO ========================= 2024-11-18 23:25:05,076 INFO Session: 2024-11-18 23:25:05,076 INFO Session(idc.benchmark.Mix2024,3,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, username -> user.181067, randomPolicyName -> testPolicy416934, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@6e2dfb17, 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, callbacks -> {"code":401,"reason":"Unauthorized","message":"User Locked Out."}, 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,1731968641252))), password -> Pa_ssw0rd, fake-ip -> 82.143.150.224, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@e23b176),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007b677469b7e0@7223478,io.netty.channel.epoll.EpollEventLoop@12dae582) 2024-11-18 23:25:05,076 INFO ========================= 2024-11-18 23:25:05,076 INFO HTTP request: 2024-11-18 23:25:05,076 INFO POST https://openam-perf-benchmark.forgeblocks.com/am/json/realms/root/authenticate 2024-11-18 23:25:05,076 INFO headers: 2024-11-18 23:25:05,076 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 23:25:05,076 INFO accept: application/json 2024-11-18 23:25:05,076 INFO content-type: application/json 2024-11-18 23:25:05,076 INFO cookie: amlbcookie=01 2024-11-18 23:25:05,076 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 23:25:05,076 INFO content-length: 2638 2024-11-18 23:25:05,076 INFO cookies: 2024-11-18 23:25:05,076 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 23:25:05,076 INFO body:StringChunksRequestBody{charset=UTF-8, content={"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiIwODQ4NDVlNC1kODRjLTQzZWUtYTE3ZC1hYjVlMjMxNWExMzUiLCJvdGsiOiJxcTVjZWhlZnRrZHJnaDRvaGU0NW9wY28xdSIsInJlYWxtIjoiLyIsInNlc3Npb25JZCI6IipBQUpUU1FBQ01ESUFCSFI1Y0dVQUNFcFhWRjlCVlZSSUFBSlRNUUFDTURFLipleUowZVhBaU9pSktWMVFpTENKamRIa2lPaUpLVjFRaUxDSmhiR2NpT2lKSVV6STFOaUo5LlpYbEtNR1ZZUVdsUGFVcExWakZSYVV4RFNteGliVTFwVDJsS1FrMVVTVFJSTUVwRVRGVm9WRTFxVlRKSmFYZHBXVmQ0YmtscWIybGFSMng1U1c0d0xpNDFUVTE0Vmw5bWNsVm1RbTF1VjFoeFRWWkVhV3BSTGtkSVYybFpXbTAxZERCRVVXOVVNV1JKYlV0d1kwbG9VbEp5YkZsT1ZFOHlOVEpFTkdseVZGWlhXSHBZYVhWVFIzVllWblZRZDFVdGNXRjFObFJvTWpjdFdHdE5VMWRzZEhsNlVreExWeTFHT0dOcE9FUkVORUZ1VVVwNVpVSkhkMGgzUzJ0S1ltbzFWRGhEYWw5UFRXZGxXVWxEVWxoNE1IRlhXRGhvVWtGQ2VqWnlkbFpIWjBsRFduQlFlR05aUlhOeVJsZHRNRTkwVFhkcFVtUkpUa05MVVV0S01tMHpUSEpNVDJOSmJqVm5NemxCVkdWcmFtdzBObXhaWjE5a2FURkxPVU5DVURkelpHWlVUMUJ2Wms1dlMzRnpja2wzTFdwTGNubzJVRWhYZEZWaldYSlZObXhSU21WcWJ6UnRja3hSWW5ObVR6RXlTMFZUVFc5QlF6azJaMkkzTVhaemEyTnJkVnAyYlU5SFExWnZlRGt6ZGtwRGVXZEhhbXhQTm5nemNWZFFlR0Z0UWpGQmVFUnFRME5vV0d4dFFVUkxWMUZ3Wkc1MWVGcExSQzFQUW1rdFJ6Rm1PSGRUYWtOaGRFdFBaekJPUlVGTFZWZHBRM0JyZFV0VVdGQm5hRFJFWmpRNE56bENOR3BCZGxsdGQwbHJWSE5yVlRSa1dWZHlXVFk1YXpBM1ZuVnhUVk15ZWpsc1pVNVRSbnB5WTBOb1R6aFhUbkZYZVhKTFVVMW1SRE5oWlc1YVdtRTFOR1pIYTB0bGQwRm5jR2c1ZG1GRFN6RkJhMWMwY1hwM09VSmllalJ0VTNONUxWTmlNWGxWVW14dGJETnpUWGs1Y1VObFFVaG1hakJNVEdSb1JHSjVhRVZQVTNWV01FZFdZbGwxVTFWcmN6SkxlbGxVYUZWbU9FczBNbE5CVTBoeFpuTkplbXBvVkdkSWVVcFJUVmRGTUUxZk56ZGlVM2xHWHpaQmJuUXlNR2hXTFhRMk16TjRhMXBHYVZWMllqSkpVVVJMTkdaRGNUQkpabEp2VmkxNFUweFdiVjl3VmpaYWJGZHRjVnBaTjFoUGJFaFJaV3BzTW10dWRYaE1URkpFTTBsV1kweHZkVTE2YkZaUE5tdFhNWEJ5YkZKdlYxbHRUUzF2VFVwRVh6aExhR1ZpWTFSVWNVMU9WblZTT0RGcU9UQjZjM1pMYUU1MVRFTkxaRGczZGtkTlEzcGxaakpDYjNNeGVqTlpjMVphYkhKdFZEVjJhRmhxUlZFMWIyOTFNWGxGWTJaVFl6SmhRbTB6WkZwSWJrbHBhazlaTTI4NE9FeFBkRUpvZFY5clZVcFhkVWRHZG1WRk1VTlpSbUpLVFRGc1NYVlhNbTgwWmpsME9HTlZkM0E1Tkd3NVdXSk9UMTg1Y0U5NmJtTlhNbmhJZG1NMWFGRnNPVXN5WVZVdWQyNTZlbVJJTFc1b1l6RTNRbnBQYzBjd2VqbFlRUS5RNEpiMUlOR3pfZDEzTGhVRUtyNnI2TXRuWW9IejRlc1hLbkhkc0xWdl9RIiwiZXhwIjoxNzMyMDU1MDQxLCJpYXQiOjE3MzE5Njg2NDF9.O3R0SySkqWxOHiGvKklRJZiHrYBHrjQ_iuTdcw_GEgQ","callbacks":[{"type":"NameCallback","output":[{"name":"prompt","value":"User Name"}],"input":[{"name":"IDToken1","value":"xiaosong.lou@pingidentity.com"}],"_id":0},{"type":"PasswordCallback","output":[{"name":"prompt","value":"Password"}],"input":[{"name":"IDToken2","value":"M@ythe4th"}],"_id":1}],"header":"Sign In","description":"Forgot username?•Forgot password?"}} 2024-11-18 23:25:05,076 INFO ========================= 2024-11-18 23:25:05,076 INFO HTTP response: 2024-11-18 23:25:05,076 INFO version: 2024-11-18 23:25:05,076 INFO HTTP/1.1 2024-11-18 23:25:05,077 INFO status: 2024-11-18 23:25:05,077 INFO 401 Unauthorized 2024-11-18 23:25:05,077 INFO headers: 2024-11-18 23:25:05,077 INFO x-frame-options: SAMEORIGIN 2024-11-18 23:25:05,077 INFO content-security-policy-report-only: frame-ancestors 'self'; script-src 'self' 'unsafe-eval' 'unsafe-inline' 2024-11-18 23:25:05,077 INFO x-content-type-options: nosniff 2024-11-18 23:25:05,077 INFO cache-control: private 2024-11-18 23:25:05,077 INFO warning: 100 OpenAM REST "No Accept-API-Version specified", 100 chf "Accept-API-Version should be included in the request." 2024-11-18 23:25:05,077 INFO content-api-version: resource=2.1 2024-11-18 23:25:05,077 INFO expires: 0 2024-11-18 23:25:05,077 INFO pragma: no-cache 2024-11-18 23:25:05,077 INFO content-type: application/json 2024-11-18 23:25:05,077 INFO date: Mon, 18 Nov 2024 22:24:01 GMT 2024-11-18 23:25:05,077 INFO x-forgerock-transactionid: 7c268225-b9d2-490e-94e6-4a0522639719 2024-11-18 23:25:05,077 INFO strict-transport-security: max-age=31536000; includeSubDomains; preload; 2024-11-18 23:25:05,077 INFO x-robots-tag: none 2024-11-18 23:25:05,077 INFO Via: 1.1 google 2024-11-18 23:25:05,077 INFO Alt-Svc: h3=":443"; ma=2592000,h3-29=":443"; ma=2592000 2024-11-18 23:25:05,077 INFO Transfer-Encoding: chunked 2024-11-18 23:25:05,077 INFO 2024-11-18 23:25:05,077 INFO body: 2024-11-18 23:25:05,077 INFO {"code":401,"reason":"Unauthorized","message":"User Locked Out."} 2024-11-18 23:25:05,077 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 23:25:05,077 INFO 22:24:01.456 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'restLoginUsernamePasswordCallback' failed for user 4: status.find.is(200), but actually found 401 2024-11-18 23:25:05,077 INFO 22:24:01.456 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 23:25:05,077 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 23:25:05,077 INFO Request: 2024-11-18 23:25:05,077 INFO restLoginUsernamePasswordCallback: KO status.find.is(200), but actually found 401 2024-11-18 23:25:05,077 INFO ========================= 2024-11-18 23:25:05,077 INFO Session: 2024-11-18 23:25:05,077 INFO Session(idc.benchmark.Mix2024,4,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, username -> user.45286, randomPolicyName -> testPolicy395426, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@1f16516b, fake-user-agent -> Mozilla/5.0 (iPhone; CPU iPhone OS 10_3 like Mac OS X) AppleWebKit/602.1.50 (KHTML, like Gecko) CriOS/56.0.2924.75 Mobile/14E5239e Safari/602.1, callbacks -> {"code":401,"reason":"Unauthorized","message":"User Locked Out."}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly,false,false,1731968641375))), password -> Pa_ssw0rd, fake-ip -> 69.88.28.194, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@1b29d651),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007b677469b7e0@7223478,io.netty.channel.epoll.EpollEventLoop@239b0f9d) 2024-11-18 23:25:05,077 INFO ========================= 2024-11-18 23:25:05,077 INFO HTTP request: 2024-11-18 23:25:05,077 INFO POST https://openam-perf-benchmark.forgeblocks.com/am/json/realms/root/authenticate 2024-11-18 23:25:05,077 INFO headers: 2024-11-18 23:25:05,077 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 23:25:05,077 INFO accept: application/json 2024-11-18 23:25:05,077 INFO content-type: application/json 2024-11-18 23:25:05,077 INFO cookie: amlbcookie=01 2024-11-18 23:25:05,077 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 23:25:05,077 INFO [...] 2024-11-18 23:25:07,588 INFO headers: 2024-11-18 23:25:07,588 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 23:25:07,588 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 23:25:07,588 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE2OTI3MzM1NSIsInN1Ym5hbWUiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJTWjFrck84R1JhMEFmd21CcU1RbnpTS2RCdlUiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NzIyNDAsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NzIyMzksInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTc1ODQwLCJpYXQiOjE3MzE5NzIyNDAsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IktMaTZCbm9QdkRlUnNfTXVfZGdEMU5fVGZFSSJ9.7mMfMyigG1Yz_B4nAw05YrrlTG-VdfzHfN70QLFAls4 2024-11-18 23:25:07,588 INFO Accept-Api-Version: 2024-11-18 23:25:07,588 INFO accept: */* 2024-11-18 23:25:07,588 INFO cookie: amlbcookie=01; 9b75a55744995d0=33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 23:25:07,588 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-18 23:25:07,588 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 23:25:07,588 INFO content-length: 0 2024-11-18 23:25:07,588 INFO cookies: 2024-11-18 23:25:07,588 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 23:25:07,588 INFO 9b75a55744995d0=33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 23:25:07,588 INFO ========================= 2024-11-18 23:25:07,588 INFO HTTP response: 2024-11-18 23:25:07,588 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 23:25:07,588 INFO 23:24:00.178 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'restLoginInitiate' failed for user 572949: j.i.IOException: Premature close 2024-11-18 23:25:07,589 INFO 23:24:00.178 [ERROR] i.g.c.a.b.SessionHookBuilder$$anon$1 - 'hook-190' crashed with 'j.u.NoSuchElementException: No attribute named 'callbacks' is defined', forwarding to the next one 2024-11-18 23:25:07,589 INFO 23:24:00.178 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 23:25:07,589 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 23:25:07,589 INFO Request: 2024-11-18 23:25:07,589 INFO restLoginInitiate: KO j.i.IOException: Premature close 2024-11-18 23:25:07,589 INFO ========================= 2024-11-18 23:25:07,589 INFO Session: 2024-11-18 23:25:07,589 INFO Session(idc.benchmark.Mix2024,572949,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, username -> user.184950, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@7a1bfdaa, fake-user-agent -> Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.157 Safari/537.36, password -> Pa_ssw0rd, fake-ip -> 209.162.39.20, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7cdf08e7),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007b677469b7e0@7223478,io.netty.channel.epoll.EpollEventLoop@619bfe29) 2024-11-18 23:25:07,589 INFO ========================= 2024-11-18 23:25:07,589 INFO HTTP request: 2024-11-18 23:25:07,589 INFO POST https://openam-perf-benchmark.forgeblocks.com/am/json/realms/root/realms/alpha/authenticate 2024-11-18 23:25:07,589 INFO headers: 2024-11-18 23:25:07,589 INFO accept: application/json 2024-11-18 23:25:07,589 INFO content-type: application/json 2024-11-18 23:25:07,589 INFO user-agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.157 Safari/537.36 2024-11-18 23:25:07,589 INFO X-Forwarded-For: 209.162.39.20 2024-11-18 23:25:07,589 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 23:25:07,589 INFO content-length: 0 2024-11-18 23:25:07,589 INFO body:StringChunksRequestBody{charset=UTF-8, content=} 2024-11-18 23:25:07,589 INFO ========================= 2024-11-18 23:25:07,589 INFO HTTP response: 2024-11-18 23:25:07,589 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 23:25:07,589 INFO 23:24:00.179 [ERROR] i.g.c.a.b.SessionHookBuilder$$anon$1 - 'hook-190' crashed with 'j.u.NoSuchElementException: No attribute named 'callbacks' is defined', forwarding to the next one 2024-11-18 23:25:07,589 INFO 23:24:00.179 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM features' failed for user 572783: j.i.IOException: Premature close 2024-11-18 23:25:07,589 INFO 23:24:00.179 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 23:25:07,589 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 23:25:07,589 INFO Request: 2024-11-18 23:25:07,589 INFO UI IDM features: KO j.i.IOException: Premature close 2024-11-18 23:25:07,589 INFO ========================= 2024-11-18 23:25:07,589 INFO Session: 2024-11-18 23:25:07,589 INFO Session(idc.benchmark.Mix2024,572783,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZTY1ZTU0MzYtMzcwYS00MzI0LTg5ZmUtNDJhMjY3ZjljYzAyLTE2OTQ3NDY5MSIsInN1Ym5hbWUiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiI1cVBQb2xaTVdGdFhXbWVsMzBjYWMxUFBVR0UiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NzIyMzksImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NzIyMzksInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTc1ODM5LCJpYXQiOjE3MzE5NzIyMzksImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6ImdyRURlY1VUU2JVeFA2M0Zva3NsSE53eW50RSJ9.NtAFxlBZ48wuROep3eWBtXjEBz7aPNV87Z5_KZzhPWU, username -> user.132396, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE2OTI3MzM1NSIsInN1Ym5hbWUiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJTWjFrck84R1JhMEFmd21CcU1RbnpTS2RCdlUiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NzIyNDAsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NzIyMzksInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTc1ODQwLCJpYXQiOjE3MzE5NzIyNDAsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IktMaTZCbm9QdkRlUnNfTXVfZGdEMU5fVGZFSSJ9.7mMfMyigG1Yz_B4nAw05YrrlTG-VdfzHfN70QLFAls4, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@839fa1f, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> KDt7aTUSqlOC6UBUXlqtiJiYwKY, callbacks -> {"tokenId":"33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731972239237), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731972239237))), user_uuid -> c91583bf-9797-4c25-95d1-beb428bb509f, password -> Pa_ssw0rd, challenge -> 5h031QWNnBV-aTlnswnKaJLnk_Ij5omWGfhtu-WBJl8, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 225.252.198.168, verifier -> MzExNzEyNTQxOTY5ODQyNTQ4Nw, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@1150dad1, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@47d5b72d, tokenId -> 33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.157 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007b677469b7e0@7223478,io.netty.channel.epoll.EpollEventLoop@4e28bdd1) 2024-11-18 23:25:07,589 INFO ========================= 2024-11-18 23:25:07,589 INFO HTTP request: 2024-11-18 23:25:07,589 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/features?_queryFilter=true 2024-11-18 23:25:07,589 INFO headers: 2024-11-18 23:25:07,589 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 23:25:07,589 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 23:25:07,589 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE2OTI3MzM1NSIsInN1Ym5hbWUiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJTWjFrck84R1JhMEFmd21CcU1RbnpTS2RCdlUiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NzIyNDAsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NzIyMzksInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTc1ODQwLCJpYXQiOjE3MzE5NzIyNDAsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IktMaTZCbm9QdkRlUnNfTXVfZGdEMU5fVGZFSSJ9.7mMfMyigG1Yz_B4nAw05YrrlTG-VdfzHfN70QLFAls4 2024-11-18 23:25:07,589 INFO Accept-Api-Version: 2024-11-18 23:25:07,589 INFO accept: */* 2024-11-18 23:25:07,589 INFO cookie: amlbcookie=01; 9b75a55744995d0=33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 23:25:07,589 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 23:25:07,589 INFO cookies: 2024-11-18 23:25:07,589 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 23:25:07,589 INFO 9b75a55744995d0=33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 23:25:07,589 INFO ========================= 2024-11-18 23:25:07,589 INFO HTTP response: 2024-11-18 23:25:07,589 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 23:25:07,590 INFO 23:24:00.179 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 572783: j.i.IOException: Premature close 2024-11-18 23:25:07,590 INFO 23:24:00.179 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 23:25:07,590 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 23:25:07,590 INFO Request: 2024-11-18 23:25:07,590 INFO UI IDM login: KO j.i.IOException: Premature close 2024-11-18 23:25:07,590 INFO ========================= 2024-11-18 23:25:07,590 INFO Session: 2024-11-18 23:25:07,590 INFO Session(idc.benchmark.Mix2024,572783,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZTY1ZTU0MzYtMzcwYS00MzI0LTg5ZmUtNDJhMjY3ZjljYzAyLTE2OTQ3NDY5MSIsInN1Ym5hbWUiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiI1cVBQb2xaTVdGdFhXbWVsMzBjYWMxUFBVR0UiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NzIyMzksImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NzIyMzksInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTc1ODM5LCJpYXQiOjE3MzE5NzIyMzksImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6ImdyRURlY1VUU2JVeFA2M0Zva3NsSE53eW50RSJ9.NtAFxlBZ48wuROep3eWBtXjEBz7aPNV87Z5_KZzhPWU, username -> user.132396, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE2OTI3MzM1NSIsInN1Ym5hbWUiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJTWjFrck84R1JhMEFmd21CcU1RbnpTS2RCdlUiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NzIyNDAsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NzIyMzksInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTc1ODQwLCJpYXQiOjE3MzE5NzIyNDAsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IktMaTZCbm9QdkRlUnNfTXVfZGdEMU5fVGZFSSJ9.7mMfMyigG1Yz_B4nAw05YrrlTG-VdfzHfN70QLFAls4, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@839fa1f, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> KDt7aTUSqlOC6UBUXlqtiJiYwKY, callbacks -> {"tokenId":"33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731972239237), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731972239237))), user_uuid -> c91583bf-9797-4c25-95d1-beb428bb509f, password -> Pa_ssw0rd, challenge -> 5h031QWNnBV-aTlnswnKaJLnk_Ij5omWGfhtu-WBJl8, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 225.252.198.168, verifier -> MzExNzEyNTQxOTY5ODQyNTQ4Nw, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@1150dad1, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@47d5b72d, tokenId -> 33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.157 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007b677469b7e0@7223478,io.netty.channel.epoll.EpollEventLoop@4e28bdd1) 2024-11-18 23:25:07,590 INFO ========================= 2024-11-18 23:25:07,590 INFO HTTP request: 2024-11-18 23:25:07,590 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-18 23:25:07,590 INFO headers: 2024-11-18 23:25:07,590 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 23:25:07,590 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 23:25:07,590 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE2OTI3MzM1NSIsInN1Ym5hbWUiOiJjOTE1ODNiZi05Nzk3LTRjMjUtOTVkMS1iZWI0MjhiYjUwOWYiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJTWjFrck84R1JhMEFmd21CcU1RbnpTS2RCdlUiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5NzIyNDAsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5NzIyMzksInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTc1ODQwLCJpYXQiOjE3MzE5NzIyNDAsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IktMaTZCbm9QdkRlUnNfTXVfZGdEMU5fVGZFSSJ9.7mMfMyigG1Yz_B4nAw05YrrlTG-VdfzHfN70QLFAls4 2024-11-18 23:25:07,590 INFO Accept-Api-Version: 2024-11-18 23:25:07,590 INFO accept: */* 2024-11-18 23:25:07,590 INFO cookie: amlbcookie=01; 9b75a55744995d0=33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 23:25:07,590 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-18 23:25:07,590 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 23:25:07,590 INFO content-length: 0 2024-11-18 23:25:07,590 INFO cookies: 2024-11-18 23:25:07,590 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 23:25:07,590 INFO 9b75a55744995d0=33TRr_4SOaj0dv4QWW3-q_sS9F8.*AAJTSQACMDIAAlNLABxWakNERWl2MUVaVms4aGlRVURqQ0J2Vk9hWXM9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 23:25:07,590 INFO ========================= 2024-11-18 23:25:07,590 INFO HTTP response: 2024-11-18 23:25:07,590 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 23:25:07,590 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 23:25:07,590 INFO [INFO] BUILD SUCCESS 2024-11-18 23:25:07,590 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 23:25:07,590 INFO [INFO] Total time: 01:00 h 2024-11-18 23:25:07,590 INFO [INFO] Finished at: 2024-11-18T23:24:00Z 2024-11-18 23:25:07,590 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 23:25:07,590 INFO 2024-11-18 23:24:00,287 INFO Gatling simulation run ended successfully 2024-11-18 23:25:07,590 INFO 2024-11-18 23:24:00,287 DEBUG No archive name provided. No archive will be created 2024-11-18 23:25:07,590 INFO ________________________________________________________________________________ [2024-11-18 23:25:07] benchmark-160 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-18 23:25:07,590 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-160/stderr.txt is small (0 lines), printing all lines: 2024-11-18 23:25:07,591 DEBUG ----- output ----- 2024-11-18 23:25:07,591 DEBUG 2024-11-18 23:25:07,591 INFO ________________________________________________________________________________ [2024-11-18 23:25:07] benchmark-160 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 23:25:28] benchmark-160 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-18 23:25:28] benchmark-160 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped