--Task-- name: benchmark-150 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-140'] preceding_task: benchmark-140 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self1r150-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 150} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-18 21:20:58] benchmark-150 pre : N/A ________________________________________________________________________________ 2024-11-18 21:20:58 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-18 21:20:58 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-150/stdout.txt Pod log : /results/orders/benchmark-150 2024-11-18 21:20:58,253 INFO 2024-11-18 21:20:58,253 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 21:20:58,253 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:58,525 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:58,525 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:58,525 DEBUG --- stdout --- 2024-11-18 21:20:58,525 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:58,525 DEBUG --- stderr --- 2024-11-18 21:20:58,525 DEBUG ________________________________________________________________________________ [2024-11-18 21:20:58] benchmark-150 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-18 21:20:58,526 INFO 2024-11-18 21:20:58,526 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 21:20:58,526 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:58,796 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:58,796 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:58,796 DEBUG --- stdout --- 2024-11-18 21:20:58,796 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:58,796 DEBUG --- stderr --- 2024-11-18 21:20:58,796 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-150" --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=self1r150- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=150 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-18 21:20:58] benchmark-150 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-18 21:20:58,797 INFO 2024-11-18 21:20:58,797 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 21:20:58,797 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:59,068 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:59,068 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:59,068 DEBUG --- stdout --- 2024-11-18 21:20:59,068 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:59,068 DEBUG --- stderr --- 2024-11-18 21:20:59,068 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/order.json { "name": "benchmark-150", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-150\" --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=self1r150- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=150 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-150" } ________________________________________________________________________________ [2024-11-18 21:20:59] benchmark-150 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 21:20:59,071 INFO 2024-11-18 21:20:59,071 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-18 21:20:59,071 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 21:20:59,284 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 21:20:59,285 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:59,285 DEBUG --- stdout --- 2024-11-18 21:20:59,285 DEBUG WAITING 2024-11-18 21:20:59,285 DEBUG --- stderr --- 2024-11-18 21:20:59,285 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 34 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-11-18 21:20:59] benchmark-150 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-18 21:20:59,285 INFO 2024-11-18 21:20:59,285 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 21:20:59,285 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:20:59,559 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:20:59,559 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:20:59,559 DEBUG --- stdout --- 2024-11-18 21:20:59,559 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:20:59,559 DEBUG --- stderr --- 2024-11-18 21:20:59,559 DEBUG 2024-11-18 21:20:59,560 INFO 2024-11-18 21:20:59,560 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/06_benchmark-150/order.json overseer-0-6644bb6697-rchxr:/results/orders/order.json 2024-11-18 21:20:59,560 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 21:21:00,897 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:21:00,897 DEBUG --- stdout --- 2024-11-18 21:21:00,897 DEBUG 2024-11-18 21:21:00,897 DEBUG --- stderr --- 2024-11-18 21:21:00,897 DEBUG Order has been successfully forwarded to overseer 2024-11-18 21:21:00 - INFO: Check if Gatling simulation started successfully 2024-11-18 21:21:00,897 INFO 2024-11-18 21:21:00,897 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 21:21:00,897 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 21:21:01,171 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 21:21:01,171 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:21:01,171 DEBUG --- stdout --- 2024-11-18 21:21:01,171 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 21:21:01,171 DEBUG --- stderr --- 2024-11-18 21:21:01,171 DEBUG 2024-11-18 21:21:01,172 INFO 2024-11-18 21:21:01,172 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-150/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-18 21:21:01,172 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 21:21:01,892 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 21:21:07,607 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 21:21:13,392 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-18 21:21:13,392 INFO [loop_until]: OK (rc = 0) 2024-11-18 21:21:13,392 DEBUG --- stdout --- 2024-11-18 21:21:13,392 DEBUG 2024-11-18 21:21:01,472 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpu1ga3qup 2024-11-18 21:21:01,474 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-150" -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=self1r150- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=150 -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/tmpu1ga3qup/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/tmpu1ga3qup/target/test-classes ... [INFO] compile in 6.9 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 21:21:13,392 DEBUG --- stderr --- 2024-11-18 21:21:13,392 DEBUG ________________________________________________________________________________ [2024-11-18 21:21:13] benchmark-150 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 04s, retry Order is running since 36m 05s, retry Order is running since 38m 05s, retry Order is running since 40m 05s, retry Order is running since 42m 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 22:21:22] benchmark-150 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-18 22:21:22,201 INFO 2024-11-18 22:21:22,201 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:21:22,201 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:22,509 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:22,509 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:22,509 DEBUG --- stdout --- 2024-11-18 22:21:22,509 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:22,509 DEBUG --- stderr --- 2024-11-18 22:21:22,509 DEBUG 2024-11-18 22:21:22,509 INFO 2024-11-18 22:21:22,510 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 22:21:22,510 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 22:21:23,267 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:23,267 DEBUG --- stdout --- 2024-11-18 22:21:23,267 DEBUG 2024-11-18 22:21:23,267 DEBUG --- stderr --- 2024-11-18 22:21:23,267 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-18 22:21:23] benchmark-150 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-18 22:21:23 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/order.json 2024-11-18 22:21:23,268 INFO 2024-11-18 22:21:23,268 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:21:23,268 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:23,558 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:23,558 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:23,558 DEBUG --- stdout --- 2024-11-18 22:21:23,558 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:23,558 DEBUG --- stderr --- 2024-11-18 22:21:23,558 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 22:21:23,559 INFO 2024-11-18 22:21:23,559 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:21:23,559 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:23,844 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:23,845 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:23,845 DEBUG --- stdout --- 2024-11-18 22:21:23,845 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:23,845 DEBUG --- stderr --- 2024-11-18 22:21:23,845 DEBUG 2024-11-18 22:21:23,845 INFO 2024-11-18 22:21:23,845 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-150/rc.txt" ]; then echo "/results/orders/benchmark-150/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-150/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-150/rc.txt file found" 2024-11-18 22:21:23,845 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 22:21:24,624 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 22:21:24,624 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:24,624 DEBUG --- stdout --- 2024-11-18 22:21:24,624 DEBUG /results/orders/benchmark-150/rc.txt file found 2024-11-18 22:21:24,624 DEBUG --- stderr --- 2024-11-18 22:21:24,624 DEBUG 2024-11-18 22:21:24,624 INFO 2024-11-18 22:21:24,624 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:21:24,625 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:24,897 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:24,897 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:24,897 DEBUG --- stdout --- 2024-11-18 22:21:24,897 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:24,897 DEBUG --- stderr --- 2024-11-18 22:21:24,897 DEBUG 2024-11-18 22:21:24,897 INFO 2024-11-18 22:21:24,897 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-150/rc.txt 2024-11-18 22:21:25,679 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 22:21:25,679 DEBUG --- stdout --- 2024-11-18 22:21:25,679 DEBUG 4 /results/orders/benchmark-150/rc.txt 2024-11-18 22:21:25,679 DEBUG --- stderr --- 2024-11-18 22:21:25,679 DEBUG 2024-11-18 22:21:25,679 INFO - Download file (size 4k) 2024-11-18 22:21:25,680 INFO 2024-11-18 22:21:25,680 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:21:25,680 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:25,953 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:25,953 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:25,953 DEBUG --- stdout --- 2024-11-18 22:21:25,953 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:25,953 DEBUG --- stderr --- 2024-11-18 22:21:25,953 DEBUG source : /results/orders/benchmark-150/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/rc.txt 2024-11-18 22:21:25,953 INFO 2024-11-18 22:21:25,953 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:21:25,953 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:26,226 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:26,226 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:26,226 DEBUG --- stdout --- 2024-11-18 22:21:26,226 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:26,226 DEBUG --- stderr --- 2024-11-18 22:21:26,226 DEBUG 2024-11-18 22:21:26,226 INFO 2024-11-18 22:21:26,226 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-150/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/rc.txt 2024-11-18 22:21:26,226 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 22:21:26,968 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:26,969 DEBUG --- stdout --- 2024-11-18 22:21:26,969 DEBUG tar: Removing leading `/' from member names 2024-11-18 22:21:26,969 DEBUG --- stderr --- 2024-11-18 22:21:26,969 DEBUG 2024-11-18 22:21:26,969 INFO 2024-11-18 22:21:26,969 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:21:26,969 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:27,241 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:27,241 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:27,241 DEBUG --- stdout --- 2024-11-18 22:21:27,241 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:27,241 DEBUG --- stderr --- 2024-11-18 22:21:27,241 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 22:21:27,241 INFO 2024-11-18 22:21:27,241 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:21:27,241 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:27,514 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:27,514 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:27,514 DEBUG --- stdout --- 2024-11-18 22:21:27,514 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:27,514 DEBUG --- stderr --- 2024-11-18 22:21:27,514 DEBUG 2024-11-18 22:21:27,515 INFO 2024-11-18 22:21:27,515 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-150/stderr.txt" ]; then echo "/results/orders/benchmark-150/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-150/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-150/stderr.txt file found" 2024-11-18 22:21:27,515 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 22:21:28,291 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 22:21:28,291 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:28,291 DEBUG --- stdout --- 2024-11-18 22:21:28,291 DEBUG /results/orders/benchmark-150/stderr.txt file found 2024-11-18 22:21:28,291 DEBUG --- stderr --- 2024-11-18 22:21:28,291 DEBUG 2024-11-18 22:21:28,292 INFO 2024-11-18 22:21:28,292 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:21:28,292 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:28,565 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:28,565 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:28,565 DEBUG --- stdout --- 2024-11-18 22:21:28,565 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:28,565 DEBUG --- stderr --- 2024-11-18 22:21:28,565 DEBUG 2024-11-18 22:21:28,565 INFO 2024-11-18 22:21:28,565 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-150/stderr.txt 2024-11-18 22:21:29,343 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 22:21:29,343 DEBUG --- stdout --- 2024-11-18 22:21:29,343 DEBUG 0 /results/orders/benchmark-150/stderr.txt 2024-11-18 22:21:29,343 DEBUG --- stderr --- 2024-11-18 22:21:29,343 DEBUG 2024-11-18 22:21:29,343 INFO - Download file (size 0k) 2024-11-18 22:21:29,343 INFO 2024-11-18 22:21:29,343 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:21:29,344 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:29,614 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:29,615 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:29,615 DEBUG --- stdout --- 2024-11-18 22:21:29,615 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:29,615 DEBUG --- stderr --- 2024-11-18 22:21:29,615 DEBUG source : /results/orders/benchmark-150/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/stderr.txt 2024-11-18 22:21:29,615 INFO 2024-11-18 22:21:29,615 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:21:29,615 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:29,885 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:29,885 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:29,885 DEBUG --- stdout --- 2024-11-18 22:21:29,885 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:29,885 DEBUG --- stderr --- 2024-11-18 22:21:29,885 DEBUG 2024-11-18 22:21:29,885 INFO 2024-11-18 22:21:29,885 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-150/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/stderr.txt 2024-11-18 22:21:29,886 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 22:21:30,628 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:30,628 DEBUG --- stdout --- 2024-11-18 22:21:30,628 DEBUG tar: Removing leading `/' from member names 2024-11-18 22:21:30,628 DEBUG --- stderr --- 2024-11-18 22:21:30,628 DEBUG 2024-11-18 22:21:30,628 INFO 2024-11-18 22:21:30,628 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 22:21:30,628 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:30,899 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:30,899 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:30,899 DEBUG --- stdout --- 2024-11-18 22:21:30,899 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:30,899 DEBUG --- stderr --- 2024-11-18 22:21:30,899 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 22:21:30,899 INFO 2024-11-18 22:21:30,899 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:21:30,899 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:31,171 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:31,171 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:31,171 DEBUG --- stdout --- 2024-11-18 22:21:31,171 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:31,171 DEBUG --- stderr --- 2024-11-18 22:21:31,171 DEBUG 2024-11-18 22:21:31,171 INFO 2024-11-18 22:21:31,171 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-150/stdout.txt" ]; then echo "/results/orders/benchmark-150/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-150/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-150/stdout.txt file found" 2024-11-18 22:21:31,172 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 22:21:31,951 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 22:21:31,952 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:31,952 DEBUG --- stdout --- 2024-11-18 22:21:31,952 DEBUG /results/orders/benchmark-150/stdout.txt file found 2024-11-18 22:21:31,952 DEBUG --- stderr --- 2024-11-18 22:21:31,952 DEBUG 2024-11-18 22:21:31,952 INFO 2024-11-18 22:21:31,952 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:21:31,952 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:32,225 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:32,225 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:32,225 DEBUG --- stdout --- 2024-11-18 22:21:32,225 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:32,225 DEBUG --- stderr --- 2024-11-18 22:21:32,225 DEBUG 2024-11-18 22:21:32,226 INFO 2024-11-18 22:21:32,226 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-150/stdout.txt 2024-11-18 22:21:33,008 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 22:21:33,008 DEBUG --- stdout --- 2024-11-18 22:21:33,008 DEBUG 2925904 /results/orders/benchmark-150/stdout.txt 2024-11-18 22:21:33,009 DEBUG --- stderr --- 2024-11-18 22:21:33,009 DEBUG 2024-11-18 22:21:33,009 INFO - Download file (size 2925904k) 2024-11-18 22:21:33,009 INFO 2024-11-18 22:21:33,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-18 22:21:33,009 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:33,285 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:33,285 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:33,285 DEBUG --- stdout --- 2024-11-18 22:21:33,285 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:33,285 DEBUG --- stderr --- 2024-11-18 22:21:33,285 DEBUG source : /results/orders/benchmark-150/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/stdout.txt 2024-11-18 22:21:33,285 INFO 2024-11-18 22:21:33,286 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:21:33,286 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:21:33,559 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:21:33,559 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:21:33,559 DEBUG --- stdout --- 2024-11-18 22:21:33,559 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:21:33,559 DEBUG --- stderr --- 2024-11-18 22:21:33,559 DEBUG 2024-11-18 22:21:33,560 INFO 2024-11-18 22:21:33,560 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-150/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/stdout.txt 2024-11-18 22:21:33,560 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 22:22:54,815 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:22:54,815 DEBUG --- stdout --- 2024-11-18 22:22:54,815 DEBUG tar: Removing leading `/' from member names 2024-11-18 22:22:54,815 DEBUG --- stderr --- 2024-11-18 22:22:54,815 DEBUG ________________________________________________________________________________ [2024-11-18 22:22:54] benchmark-150 show_cmd : Display order command ________________________________________________________________________________ 2024-11-18 22:22:54,816 INFO 2024-11-18 22:22:54,816 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:22:54,816 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:22:55,088 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:22:55,089 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:22:55,089 DEBUG --- stdout --- 2024-11-18 22:22:55,089 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:22:55,089 DEBUG --- stderr --- 2024-11-18 22:22:55,089 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 22:22:55,089 INFO 2024-11-18 22:22:55,089 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:22:55,089 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:22:55,360 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:22:55,360 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:22:55,360 DEBUG --- stdout --- 2024-11-18 22:22:55,360 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:22:55,360 DEBUG --- stderr --- 2024-11-18 22:22:55,360 DEBUG 2024-11-18 22:22:55,361 INFO 2024-11-18 22:22:55,361 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-150/cmd.txt" ]; then echo "/results/orders/benchmark-150/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-150/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-150/cmd.txt file found" 2024-11-18 22:22:55,361 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 22:22:56,129 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 22:22:56,129 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:22:56,129 DEBUG --- stdout --- 2024-11-18 22:22:56,129 DEBUG /results/orders/benchmark-150/cmd.txt file found 2024-11-18 22:22:56,129 DEBUG --- stderr --- 2024-11-18 22:22:56,129 DEBUG 2024-11-18 22:22:56,129 INFO 2024-11-18 22:22:56,129 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:22:56,129 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:22:56,400 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:22:56,400 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:22:56,400 DEBUG --- stdout --- 2024-11-18 22:22:56,400 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:22:56,400 DEBUG --- stderr --- 2024-11-18 22:22:56,400 DEBUG 2024-11-18 22:22:56,400 INFO 2024-11-18 22:22:56,400 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-150/cmd.txt 2024-11-18 22:22:57,180 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 22:22:57,180 DEBUG --- stdout --- 2024-11-18 22:22:57,180 DEBUG 4 /results/orders/benchmark-150/cmd.txt 2024-11-18 22:22:57,180 DEBUG --- stderr --- 2024-11-18 22:22:57,180 DEBUG 2024-11-18 22:22:57,180 INFO - Download file (size 4k) 2024-11-18 22:22:57,180 INFO 2024-11-18 22:22:57,180 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:22:57,181 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:22:57,456 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:22:57,456 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:22:57,456 DEBUG --- stdout --- 2024-11-18 22:22:57,456 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:22:57,456 DEBUG --- stderr --- 2024-11-18 22:22:57,457 DEBUG source : /results/orders/benchmark-150/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/cmd.txt 2024-11-18 22:22:57,457 INFO 2024-11-18 22:22:57,457 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:22:57,457 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 22:22:57,728 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 22:22:57,728 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:22:57,728 DEBUG --- stdout --- 2024-11-18 22:22:57,728 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 22:22:57,728 DEBUG --- stderr --- 2024-11-18 22:22:57,728 DEBUG 2024-11-18 22:22:57,728 INFO 2024-11-18 22:22:57,728 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-150/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/cmd.txt 2024-11-18 22:22:57,728 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 22:22:58,467 INFO [loop_until]: OK (rc = 0) 2024-11-18 22:22:58,467 DEBUG --- stdout --- 2024-11-18 22:22:58,467 DEBUG tar: Removing leading `/' from member names 2024-11-18 22:22:58,467 DEBUG --- stderr --- 2024-11-18 22:22:58,467 DEBUG 2024-11-18 22:22:58,467 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/cmd.txt is small (1 lines), printing all lines: 2024-11-18 22:22:58,467 DEBUG ----- output ----- 2024-11-18 22:22:58,467 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-150" --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=self1r150- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=150 -Duser_password=Pa_ssw0rd" 2024-11-18 22:22:58,467 INFO ________________________________________________________________________________ [2024-11-18 22:22:58] benchmark-150 show_rc : Display rc order ________________________________________________________________________________ 2024-11-18 22:22:58,468 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/rc.txt is small (1 lines), printing all lines: 2024-11-18 22:22:58,468 DEBUG ----- output ----- 2024-11-18 22:22:58,468 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-18 22:22:58,468 INFO ________________________________________________________________________________ [2024-11-18 22:22:58] benchmark-150 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-18 22:23:02,018 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/06_benchmark-150/stdout.txt is 26753449 lines): 2024-11-18 22:23:02,018 DEBUG ----- output ----- 2024-11-18 22:23:02,018 INFO 2024-11-18 21:21:01,472 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpu1ga3qup 2024-11-18 22:23:02,018 INFO 2024-11-18 21:21:01,474 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-150" -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=self1r150- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=150 -Duser_password=**** 2024-11-18 22:23:02,018 INFO [INFO] Scanning for projects... 2024-11-18 22:23:02,018 INFO [INFO] 2024-11-18 22:23:02,018 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-18 22:23:02,018 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-18 22:23:02,018 INFO [INFO] from pom.xml 2024-11-18 22:23:02,018 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-18 22:23:02,018 INFO [INFO] 2024-11-18 22:23:02,018 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-18 22:23:02,018 INFO [INFO] 2024-11-18 22:23:02,018 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-18 22:23:02,018 INFO [INFO] skip non existing resourceDirectory /tmp/tmpu1ga3qup/src/main/resources 2024-11-18 22:23:02,018 INFO [INFO] 2024-11-18 22:23:02,018 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-18 22:23:02,018 INFO [INFO] No sources to compile 2024-11-18 22:23:02,018 INFO [INFO] 2024-11-18 22:23:02,018 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-18 22:23:02,018 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-18 22:23:02,018 INFO [INFO] 2024-11-18 22:23:02,018 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-18 22:23:02,018 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-18 22:23:02,018 INFO [INFO] 2024-11-18 22:23:02,018 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-18 22:23:02,018 INFO [INFO] compiling 38 Scala sources to /tmp/tmpu1ga3qup/target/test-classes ... 2024-11-18 22:23:02,018 INFO [INFO] compile in 6.9 s 2024-11-18 22:23:02,018 INFO [INFO] 2024-11-18 22:23:02,019 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-18 22:23:02,019 INFO [INFO] 2024-11-18 22:23:02,019 INFO [INFO] 2024-11-18 22:23:02,019 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-18 22:23:02,019 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-18 22:23:02,019 INFO Gatling 3.13.1 is available! (you're using 3.12.0) 2024-11-18 22:23:02,019 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-18 22:23:02,019 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-18 22:23:02,019 INFO 21:21:16.012 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 22:23:02,019 INFO 21:21:16.249 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 22:23:02,019 INFO 21:21:16.493 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 22:23:02,019 INFO 21:21:17.292 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 22:23:02,019 INFO 21:21:17.340 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 22:23:02,019 INFO 2024-11-18 22:23:02,019 INFO ================================================================================ 2024-11-18 22:23:02,019 INFO 2024-11-18 21:21:17 GMT 5s elapsed 2024-11-18 22:23:02,019 INFO ---- Requests ------------------------------------------------------------------ 2024-11-18 22:23:02,019 INFO > Global (OK=630 KO=0 ) 2024-11-18 22:23:02,019 INFO > restLoginInitiate (OK=60 KO=0 ) 2024-11-18 22:23:02,019 INFO > restLoginUsernamePasswordCallback (OK=58 KO=0 ) 2024-11-18 22:23:02,019 INFO > Skip 2FA (OK=30 KO=0 ) 2024-11-18 22:23:02,019 INFO > User Authorize PKCE stage (OK=63 KO=0 ) 2024-11-18 22:23:02,019 INFO > User AccessToken PKCE stage (OK=62 KO=0 ) 2024-11-18 22:23:02,019 INFO > RootUserinfo (OK=11 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI-Post-Login - stage 1 (OK=12 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=12 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI AM idFromSession (OK=12 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI AM validateGoto (OK=12 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI-Post-Login - stage 2 (OK=11 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=11 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI Enduser appAuthHelper1 (OK=11 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI Enduser appAuthHelper2 (OK=11 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI-Post-Login - stage 3 (OK=10 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=10 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI Enduser sessioncheck (OK=10 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI IDM uiconfig (OK=10 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI IDM version (OK=10 KO=0 ) 2024-11-18 22:23:02,019 INFO > UI IDM features (OK=9 KO=0 ) 2024-11-18 22:23:02,020 INFO > UI IDM privilege (OK=10 KO=0 ) 2024-11-18 22:23:02,020 INFO > UI IDM login (OK=10 KO=0 ) 2024-11-18 22:23:02,020 INFO > UI IDM managed user schema (OK=9 KO=0 ) 2024-11-18 22:23:02,020 INFO > UI IDM user (OK=9 KO=0 ) 2024-11-18 22:23:02,020 INFO > UI IDM dashboard (OK=9 KO=0 ) 2024-11-18 22:23:02,020 INFO > tokenUserinfo (OK=9 KO=0 ) 2024-11-18 22:23:02,020 INFO > UI IDM oidcToken (OK=9 KO=0 ) 2024-11-18 22:23:02,020 INFO > userinfo (OK=9 KO=0 ) 2024-11-18 22:23:02,020 INFO > UI-Logout (OK=9 KO=0 ) 2024-11-18 22:23:02,020 INFO > UI-Logout Redirect 1 (OK=8 KO=0 ) 2024-11-18 22:23:02,020 INFO > UI AM revoke2 (OK=8 KO=0 ) 2024-11-18 22:23:02,020 INFO > UI AM endsession (OK=8 KO=0 ) 2024-11-18 22:23:02,020 INFO > GetUUid (OK=18 KO=0 ) 2024-11-18 22:23:02,020 INFO > UI AM revoke1 (OK=8 KO=0 ) 2024-11-18 22:23:02,020 INFO > GetOpenidConfig (OK=18 KO=0 ) 2024-11-18 22:23:02,020 INFO > getIdToken (OK=5 KO=0 ) 2024-11-18 22:23:02,020 INFO > CreatePolicy (OK=18 KO=0 ) 2024-11-18 22:23:02,020 INFO > getIdToken Redirect 1 (OK=5 KO=0 ) 2024-11-18 22:23:02,020 INFO > createOrganization (OK=5 KO=0 ) 2024-11-18 22:23:02,020 INFO > queryOrganization (OK=4 KO=0 ) 2024-11-18 22:23:02,020 INFO > deleteOrganization (OK=4 KO=0 ) 2024-11-18 22:23:02,020 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-11-18 22:23:02,020 INFO > loginTreeWorkflow_restLoginInitiate (OK=1 KO=0 ) 2024-11-18 22:23:02,020 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-11-18 22:23:02,020 INFO ---- Errors -------------------------------------------------------------------- 2024-11-18 22:23:02,020 INFO > tokenUserinfo: No attribute named 'access_token' is defined 5 (100.0%) 2024-11-18 22:23:02,020 INFO 2024-11-18 22:23:02,020 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-18 22:23:02,020 INFO [- ] 0% 2024-11-18 22:23:02,020 INFO waiting: 542978 / active: 34 / done: 28 2024-11-18 22:23:02,020 INFO ================================================================================ 2024-11-18 22:23:02,020 INFO 2024-11-18 22:23:02,020 INFO 21:21:18.282 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 22:23:02,020 INFO 21:21:18.663 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 22:23:02,020 INFO 21:21:18.815 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-18 22:23:02,020 INFO [...] 2024-11-18 22:23:06,666 INFO HTTP request: 2024-11-18 22:23:06,666 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-18 22:23:06,666 INFO headers: 2024-11-18 22:23:06,666 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 22:23:06,666 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 22:23:06,666 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NjI4NGVmZS1jN2Y1LTRlYzAtOTk4NS1mNjM3YTgwN2ZjNmIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE1NTAxMzg3MiIsInN1Ym5hbWUiOiI3NjI4NGVmZS1jN2Y1LTRlYzAtOTk4NS1mNjM3YTgwN2ZjNmIiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJpU0haYnlBN3J5UWhpQzFnMWw0V0RqeXF6Y0UiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5Njg0NzIsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5Njg0NzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTcyMDcyLCJpYXQiOjE3MzE5Njg0NzIsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IlBFcHQxdUVsSjR3NnQxR1lOa1RydGg0SWxQayJ9.-43NVP1Tdc7nMJssRthFs148HQ6WYlQ7FpaSvl7sl2s 2024-11-18 22:23:06,666 INFO Accept-Api-Version: 2024-11-18 22:23:06,666 INFO accept: */* 2024-11-18 22:23:06,666 INFO cookie: amlbcookie=01; 9b75a55744995d0=DM9__cvSqf43afS0G_7m6K1bKbg.*AAJTSQACMDIAAlNLABxCSjNZQzdScWZzbDM1bjlCYmUvL1BVWndaQU09AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 22:23:06,666 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-18 22:23:06,666 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 22:23:06,666 INFO content-length: 0 2024-11-18 22:23:06,666 INFO cookies: 2024-11-18 22:23:06,666 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 22:23:06,666 INFO 9b75a55744995d0=DM9__cvSqf43afS0G_7m6K1bKbg.*AAJTSQACMDIAAlNLABxCSjNZQzdScWZzbDM1bjlCYmUvL1BVWndaQU09AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 22:23:06,666 INFO ========================= 2024-11-18 22:23:06,666 INFO HTTP response: 2024-11-18 22:23:06,666 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 22:23:06,666 INFO 22:21:12.396 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM features' failed for user 538418: j.i.IOException: Premature close 2024-11-18 22:23:06,666 INFO 22:21:12.396 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 22:23:06,666 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 22:23:06,666 INFO Request: 2024-11-18 22:23:06,666 INFO UI IDM features: KO j.i.IOException: Premature close 2024-11-18 22:23:06,666 INFO ========================= 2024-11-18 22:23:06,666 INFO Session: 2024-11-18 22:23:06,667 INFO Session(idc.benchmark.Mix2024,538418,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NjI4NGVmZS1jN2Y1LTRlYzAtOTk4NS1mNjM3YTgwN2ZjNmIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZTY1ZTU0MzYtMzcwYS00MzI0LTg5ZmUtNDJhMjY3ZjljYzAyLTE1NTA5ODI0MiIsInN1Ym5hbWUiOiI3NjI4NGVmZS1jN2Y1LTRlYzAtOTk4NS1mNjM3YTgwN2ZjNmIiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiIxNy1lVzB6YnI0SlB0Uzk2RnFqS19NV1JqZ1kiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5Njg0NzEsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5Njg0NzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTcyMDcxLCJpYXQiOjE3MzE5Njg0NzEsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IjRuWUFpdmx4WDd3aVdfSmYwcmF1MUJiVGFuayJ9.nRWUj34Gmae2cb1gUGtCAMy_lZy9gn9C-jVKKHwgnOk, username -> user.96231, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NjI4NGVmZS1jN2Y1LTRlYzAtOTk4NS1mNjM3YTgwN2ZjNmIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE1NTAxMzg3MiIsInN1Ym5hbWUiOiI3NjI4NGVmZS1jN2Y1LTRlYzAtOTk4NS1mNjM3YTgwN2ZjNmIiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJpU0haYnlBN3J5UWhpQzFnMWw0V0RqeXF6Y0UiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5Njg0NzIsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5Njg0NzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTcyMDcyLCJpYXQiOjE3MzE5Njg0NzIsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IlBFcHQxdUVsSjR3NnQxR1lOa1RydGg0SWxQayJ9.-43NVP1Tdc7nMJssRthFs148HQ6WYlQ7FpaSvl7sl2s, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@1cef040d, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> G7mUVa59mQz9M9qEdJB9P14Ov4c, callbacks -> {"tokenId":"DM9__cvSqf43afS0G_7m6K1bKbg.*AAJTSQACMDIAAlNLABxCSjNZQzdScWZzbDM1bjlCYmUvL1BVWndaQU09AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731968471588), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=DM9__cvSqf43afS0G_7m6K1bKbg.*AAJTSQACMDIAAlNLABxCSjNZQzdScWZzbDM1bjlCYmUvL1BVWndaQU09AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731968471588))), user_uuid -> 76284efe-c7f5-4ec0-9985-f637a807fc6b, password -> Pa_ssw0rd, challenge -> yWI2pT7kP5qWBPyuBKNqSU3dbgtOIuRjiIQtrSwn02A, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 27.184.129.113, verifier -> NzQxMTU3NzY2MDQzNzM2MzEwMw, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@64a1800, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@7ec0b5b1, tokenId -> DM9__cvSqf43afS0G_7m6K1bKbg.*AAJTSQACMDIAAlNLABxCSjNZQzdScWZzbDM1bjlCYmUvL1BVWndaQU09AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Linux; Android 6.0; Nexus 5 Build/MRA58N) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.85 Mobile Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007b7f8c69a0e0@205c0005,io.netty.channel.epoll.EpollEventLoop@14f5da2c) 2024-11-18 22:23:06,667 INFO ========================= 2024-11-18 22:23:06,667 INFO HTTP request: 2024-11-18 22:23:06,667 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/features?_queryFilter=true 2024-11-18 22:23:06,667 INFO headers: 2024-11-18 22:23:06,667 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 22:23:06,667 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 22:23:06,667 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI3NjI4NGVmZS1jN2Y1LTRlYzAtOTk4NS1mNjM3YTgwN2ZjNmIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE1NTAxMzg3MiIsInN1Ym5hbWUiOiI3NjI4NGVmZS1jN2Y1LTRlYzAtOTk4NS1mNjM3YTgwN2ZjNmIiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJpU0haYnlBN3J5UWhpQzFnMWw0V0RqeXF6Y0UiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5Njg0NzIsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5Njg0NzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTcyMDcyLCJpYXQiOjE3MzE5Njg0NzIsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IlBFcHQxdUVsSjR3NnQxR1lOa1RydGg0SWxQayJ9.-43NVP1Tdc7nMJssRthFs148HQ6WYlQ7FpaSvl7sl2s 2024-11-18 22:23:06,667 INFO Accept-Api-Version: 2024-11-18 22:23:06,667 INFO accept: */* 2024-11-18 22:23:06,667 INFO cookie: amlbcookie=01; 9b75a55744995d0=DM9__cvSqf43afS0G_7m6K1bKbg.*AAJTSQACMDIAAlNLABxCSjNZQzdScWZzbDM1bjlCYmUvL1BVWndaQU09AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 22:23:06,667 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 22:23:06,667 INFO cookies: 2024-11-18 22:23:06,667 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 22:23:06,667 INFO 9b75a55744995d0=DM9__cvSqf43afS0G_7m6K1bKbg.*AAJTSQACMDIAAlNLABxCSjNZQzdScWZzbDM1bjlCYmUvL1BVWndaQU09AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 22:23:06,667 INFO ========================= 2024-11-18 22:23:06,667 INFO HTTP response: 2024-11-18 22:23:06,667 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 22:23:06,667 INFO 22:21:12.396 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'restLoginInitiate' failed for user 538547: j.n.c.ClosedChannelException 2024-11-18 22:23:06,667 INFO 22:21:12.396 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 22:23:06,667 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 22:23:06,667 INFO Request: 2024-11-18 22:23:06,667 INFO restLoginInitiate: KO j.n.c.ClosedChannelException 2024-11-18 22:23:06,667 INFO ========================= 2024-11-18 22:23:06,667 INFO Session: 2024-11-18 22:23:06,667 INFO Session(idc.benchmark.Mix2024,538547,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, username -> user.6979, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@65313528, fake-user-agent -> Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.3, password -> Pa_ssw0rd, fake-ip -> 156.116.39.252, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7da75009),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007b7f8c69a0e0@205c0005,io.netty.channel.epoll.EpollEventLoop@12dae582) 2024-11-18 22:23:06,667 INFO ========================= 2024-11-18 22:23:06,667 INFO HTTP request: 2024-11-18 22:23:06,667 INFO POST https://openam-perf-benchmark.forgeblocks.com/am/json/realms/root/realms/alpha/authenticate 2024-11-18 22:23:06,667 INFO headers: 2024-11-18 22:23:06,667 INFO accept: application/json 2024-11-18 22:23:06,667 INFO content-type: application/json 2024-11-18 22:23:06,667 INFO user-agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.3 2024-11-18 22:23:06,667 INFO X-Forwarded-For: 156.116.39.252 2024-11-18 22:23:06,667 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 22:23:06,667 INFO body:StringChunksRequestBody{charset=UTF-8, content=} 2024-11-18 22:23:06,667 INFO ========================= 2024-11-18 22:23:06,667 INFO HTTP response: 2024-11-18 22:23:06,667 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 22:23:06,667 INFO 22:21:12.396 [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 22:23:06,667 INFO 22:21:12.396 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 538403: j.i.IOException: Premature close 2024-11-18 22:23:06,667 INFO 22:21:12.396 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 22:23:06,667 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 22:23:06,667 INFO Request: 2024-11-18 22:23:06,667 INFO UI IDM login: KO j.i.IOException: Premature close 2024-11-18 22:23:06,667 INFO ========================= 2024-11-18 22:23:06,667 INFO Session: 2024-11-18 22:23:06,668 INFO Session(idc.benchmark.Mix2024,538403,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIwMDNiZTA5MC1mNDJiLTQ3ZDQtYjA0Ny0wZTc3MTk4Yjc1ZjAiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE1NTAxMjcwMyIsInN1Ym5hbWUiOiIwMDNiZTA5MC1mNDJiLTQ3ZDQtYjA0Ny0wZTc3MTk4Yjc1ZjAiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiJPUzdIT3lZMmRubWtMN0RsUVJaNEpWNEx6amMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5Njg0NzEsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJvcGVuaWQiLCJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5Njg0NzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTcyMDcxLCJpYXQiOjE3MzE5Njg0NzEsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6InJzUlRvTllYTXh5YU02QWZ4X2R0NnVaRnY4USJ9.h7MvhihhzDIOUF1lfIw5hD2oweHbR8XZki0G2qDjLZw, username -> user.28637, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIwMDNiZTA5MC1mNDJiLTQ3ZDQtYjA0Ny0wZTc3MTk4Yjc1ZjAiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE1NTAxMzg3MSIsInN1Ym5hbWUiOiIwMDNiZTA5MC1mNDJiLTQ3ZDQtYjA0Ny0wZTc3MTk4Yjc1ZjAiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiIwMzQxQkw5LVFWRmVuMnN3WEpZQS1YNEpVRmMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5Njg0NzIsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5Njg0NzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTcyMDcyLCJpYXQiOjE3MzE5Njg0NzIsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IlI4RnR1YWF6cWFtR3lNQklZUEpqUzlsbFV6TSJ9.FHRl6MSOx0PgumjK9YyUPSVwVfAWQ5jkDr-QSdmdj-A, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@111f4b24, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> x-Uipsc_jUDsd9LNG2EWqyHOuK0, callbacks -> {"tokenId":"JiOSmDTRDM4Pr88J-yi_vT8rurs.*AAJTSQACMDIAAlNLABxKTjhPUUhnNm1XUS95WWp3a0x1UUxtMDFMZmc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","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,1731968471503), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=JiOSmDTRDM4Pr88J-yi_vT8rurs.*AAJTSQACMDIAAlNLABxKTjhPUUhnNm1XUS95WWp3a0x1UUxtMDFMZmc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731968471503))), user_uuid -> 003be090-f42b-47d4-b047-0e77198b75f0, password -> Pa_ssw0rd, challenge -> 3nsFrlsu3nOyv3RrZwUg2wFyk7PVRt6rP6CgOHFYlVU, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 99.61.217.207, verifier -> LTQ4Mzk3MTMxNjI5NDk3MjYyMTg, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@6ea9c58b, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@187d9298, tokenId -> JiOSmDTRDM4Pr88J-yi_vT8rurs.*AAJTSQACMDIAAlNLABxKTjhPUUhnNm1XUS95WWp3a0x1UUxtMDFMZmc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, 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/0x00007b7f8c69a0e0@205c0005,io.netty.channel.epoll.EpollEventLoop@12dae582) 2024-11-18 22:23:06,668 INFO ========================= 2024-11-18 22:23:06,668 INFO HTTP request: 2024-11-18 22:23:06,668 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-18 22:23:06,668 INFO headers: 2024-11-18 22:23:06,668 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 22:23:06,668 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 22:23:06,668 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIwMDNiZTA5MC1mNDJiLTQ3ZDQtYjA0Ny0wZTc3MTk4Yjc1ZjAiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTE1NTAxMzg3MSIsInN1Ym5hbWUiOiIwMDNiZTA5MC1mNDJiLTQ3ZDQtYjA0Ny0wZTc3MTk4Yjc1ZjAiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLWJlbmNobWFyay5mb3JnZWJsb2Nrcy5jb206NDQzL2FtL29hdXRoMi9yZWFsbXMvcm9vdC9yZWFsbXMvYWxwaGEiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiIwMzQxQkw5LVFWRmVuMnN3WEpZQS1YNEpVRmMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJlbmRVc2VyVUlDbGllbnQiLCJhdWQiOiJlbmRVc2VyVUlDbGllbnQiLCJuYmYiOjE3MzE5Njg0NzIsImdyYW50X3R5cGUiOiJhdXRob3JpemF0aW9uX2NvZGUiLCJzY29wZSI6WyJmcjppZG06KiJdLCJhdXRoX3RpbWUiOjE3MzE5Njg0NzEsInJlYWxtIjoiL2FscGhhIiwiZXhwIjoxNzMxOTcyMDcyLCJpYXQiOjE3MzE5Njg0NzIsImV4cGlyZXNfaW4iOjM2MDAsImp0aSI6IlI4RnR1YWF6cWFtR3lNQklZUEpqUzlsbFV6TSJ9.FHRl6MSOx0PgumjK9YyUPSVwVfAWQ5jkDr-QSdmdj-A 2024-11-18 22:23:06,668 INFO Accept-Api-Version: 2024-11-18 22:23:06,668 INFO accept: */* 2024-11-18 22:23:06,668 INFO cookie: amlbcookie=01; 9b75a55744995d0=JiOSmDTRDM4Pr88J-yi_vT8rurs.*AAJTSQACMDIAAlNLABxKTjhPUUhnNm1XUS95WWp3a0x1UUxtMDFMZmc9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 22:23:06,668 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-18 22:23:06,668 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 22:23:06,668 INFO content-length: 0 2024-11-18 22:23:06,668 INFO cookies: 2024-11-18 22:23:06,668 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 22:23:06,668 INFO 9b75a55744995d0=JiOSmDTRDM4Pr88J-yi_vT8rurs.*AAJTSQACMDIAAlNLABxKTjhPUUhnNm1XUS95WWp3a0x1UUxtMDFMZmc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 22:23:06,668 INFO ========================= 2024-11-18 22:23:06,668 INFO HTTP response: 2024-11-18 22:23:06,668 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 22:23:06,668 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 22:23:06,668 INFO [INFO] BUILD SUCCESS 2024-11-18 22:23:06,668 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 22:23:06,668 INFO [INFO] Total time: 01:00 h 2024-11-18 22:23:06,668 INFO [INFO] Finished at: 2024-11-18T22:21:12Z 2024-11-18 22:23:06,668 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 22:23:06,668 INFO 2024-11-18 22:21:12,927 INFO Gatling simulation run ended successfully 2024-11-18 22:23:06,668 INFO 2024-11-18 22:21:12,927 DEBUG No archive name provided. No archive will be created 2024-11-18 22:23:06,668 INFO ________________________________________________________________________________ [2024-11-18 22:23:06] benchmark-150 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-18 22:23:06,668 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/06_benchmark-150/stderr.txt is small (0 lines), printing all lines: 2024-11-18 22:23:06,668 DEBUG ----- output ----- 2024-11-18 22:23:06,668 DEBUG 2024-11-18 22:23:06,668 INFO ________________________________________________________________________________ [2024-11-18 22:23:06] benchmark-150 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 22:23:44] benchmark-150 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-18 22:23:44] benchmark-150 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped