--Task-- name: benchmark-10 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: [] preceding_task: None options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 10} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-06 20:11:05] benchmark-10 pre : N/A ________________________________________________________________________________ 2024-11-06 20:11:05 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-06 20:11:05 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-10/stdout.txt Pod log : /results/orders/benchmark-10 2024-11-06 20:11:05,390 INFO 2024-11-06 20:11:05,390 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 20:11:05,390 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 20:11:05,666 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 20:11:05,667 INFO [loop_until]: OK (rc = 0) 2024-11-06 20:11:05,667 DEBUG --- stdout --- 2024-11-06 20:11:05,667 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 20:11:05,667 DEBUG --- stderr --- 2024-11-06 20:11:05,667 DEBUG 2024-11-06 20:11:05 - INFO: Get overseer pod resources requests memory 2024-11-06 20:11:05,667 INFO 2024-11-06 20:11:05,667 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-6644bb6697-9gfkx --output jsonpath={.spec.containers[].resources.requests.memory} 2024-11-06 20:11:05,667 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 20:11:05,962 INFO [loop_until]: OK (rc = 0) 2024-11-06 20:11:05,962 DEBUG --- stdout --- 2024-11-06 20:11:05,962 DEBUG 4Gi 2024-11-06 20:11:05,962 DEBUG --- stderr --- 2024-11-06 20:11:05,962 DEBUG ________________________________________________________________________________ [2024-11-06 20:11:05] benchmark-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-06 20:11:05,963 INFO 2024-11-06 20:11:05,963 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 20:11:05,963 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 20:11:06,240 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 20:11:06,240 INFO [loop_until]: OK (rc = 0) 2024-11-06 20:11:06,240 DEBUG --- stdout --- 2024-11-06 20:11:06,240 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 20:11:06,240 DEBUG --- stderr --- 2024-11-06 20:11:06,240 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-10" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-06 20:11:06] benchmark-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-06 20:11:06,241 INFO 2024-11-06 20:11:06,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-06 20:11:06,241 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 20:11:06,516 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 20:11:06,517 INFO [loop_until]: OK (rc = 0) 2024-11-06 20:11:06,517 DEBUG --- stdout --- 2024-11-06 20:11:06,517 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 20:11:06,517 DEBUG --- stderr --- 2024-11-06 20:11:06,517 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/order.json { "name": "benchmark-10", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-10\" --no-reports --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx4G\" --simulation-system-properties=\"-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-10" } ________________________________________________________________________________ [2024-11-06 20:11:06] benchmark-10 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-11-06 20:11:06,520 INFO 2024-11-06 20:11:06,520 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-06 20:11:06,520 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-06 20:11:06,775 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-06 20:11:06,775 INFO [loop_until]: OK (rc = 0) 2024-11-06 20:11:06,775 DEBUG --- stdout --- 2024-11-06 20:11:06,775 DEBUG WAITING 2024-11-06 20:11:06,775 DEBUG --- stderr --- 2024-11-06 20:11:06,775 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 28 0 --:--:-- --:--:-- --:--:-- 28 ________________________________________________________________________________ [2024-11-06 20:11:06] benchmark-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-06 20:11:06,776 INFO 2024-11-06 20:11:06,776 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 20:11:06,776 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 20:11:07,052 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 20:11:07,053 INFO [loop_until]: OK (rc = 0) 2024-11-06 20:11:07,053 DEBUG --- stdout --- 2024-11-06 20:11:07,053 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 20:11:07,053 DEBUG --- stderr --- 2024-11-06 20:11:07,053 DEBUG 2024-11-06 20:11:07,053 INFO 2024-11-06 20:11:07,053 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/01_benchmark-10/order.json overseer-0-6644bb6697-9gfkx:/results/orders/order.json 2024-11-06 20:11:07,053 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 20:11:08,438 INFO [loop_until]: OK (rc = 0) 2024-11-06 20:11:08,438 DEBUG --- stdout --- 2024-11-06 20:11:08,438 DEBUG 2024-11-06 20:11:08,438 DEBUG --- stderr --- 2024-11-06 20:11:08,438 DEBUG Order has been successfully forwarded to overseer 2024-11-06 20:11:08 - INFO: Check if Gatling simulation started successfully 2024-11-06 20:11:08,438 INFO 2024-11-06 20:11:08,438 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 20:11:08,438 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 20:11:08,716 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 20:11:08,716 INFO [loop_until]: OK (rc = 0) 2024-11-06 20:11:08,716 DEBUG --- stdout --- 2024-11-06 20:11:08,717 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 20:11:08,717 DEBUG --- stderr --- 2024-11-06 20:11:08,717 DEBUG 2024-11-06 20:11:08,717 INFO 2024-11-06 20:11:08,717 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx -- head --lines 5000 /results/orders/benchmark-10/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-06 20:11:08,717 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-06 20:11:09,452 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-06 20:11:15,195 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-06 20:11:20,949 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-06 20:11:20,949 INFO [loop_until]: OK (rc = 0) 2024-11-06 20:11:20,949 DEBUG --- stdout --- 2024-11-06 20:11:20,949 DEBUG 2024-11-06 20:11:09,372 DEBUG Creating results folder /results/gatling/benchmark-10 2024-11-06 20:11:09,380 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp6vrc9hsu 2024-11-06 20:11:09,381 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-10" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=10 -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/tmp6vrc9hsu/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/tmp6vrc9hsu/target/test-classes ... [INFO] compile in 6.8 s [INFO] [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< [INFO] [INFO] [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- [INFO] Running simulation com.forgerock.pyrock.Loadtest. System property think_time_multiplier not set. No think time emulation will be done in the simulation. Simulation com.forgerock.pyrock.Loadtest started... 2024-11-06 20:11:20,949 DEBUG --- stderr --- 2024-11-06 20:11:20,949 DEBUG ________________________________________________________________________________ [2024-11-06 20:11:20] benchmark-10 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 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry PASS : Order stopped by itself after 1h and before timeout (1h 12m) ________________________________________________________________________________ [2024-11-06 21:11:30] benchmark-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-06 21:11:30,780 INFO 2024-11-06 21:11:30,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-06 21:11:30,780 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:31,082 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:31,082 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:31,082 DEBUG --- stdout --- 2024-11-06 21:11:31,082 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:31,082 DEBUG --- stderr --- 2024-11-06 21:11:31,082 DEBUG 2024-11-06 21:11:31,082 INFO 2024-11-06 21:11:31,082 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx -- rm -f /results/orders/order.json 2024-11-06 21:11:31,082 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 21:11:32,046 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:32,047 DEBUG --- stdout --- 2024-11-06 21:11:32,047 DEBUG 2024-11-06 21:11:32,047 DEBUG --- stderr --- 2024-11-06 21:11:32,047 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-06 21:11:32] benchmark-10 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-06 21:11:32 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/order.json 2024-11-06 21:11:32,048 INFO 2024-11-06 21:11:32,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-06 21:11:32,048 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:32,328 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:32,328 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:32,328 DEBUG --- stdout --- 2024-11-06 21:11:32,328 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:32,328 DEBUG --- stderr --- 2024-11-06 21:11:32,328 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-06 21:11:32,329 INFO 2024-11-06 21:11:32,329 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:32,329 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:32,604 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:32,604 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:32,604 DEBUG --- stdout --- 2024-11-06 21:11:32,604 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:32,604 DEBUG --- stderr --- 2024-11-06 21:11:32,604 DEBUG 2024-11-06 21:11:32,605 INFO 2024-11-06 21:11:32,605 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-10/rc.txt" ]; then echo "/results/orders/benchmark-10/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-10/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-10/rc.txt file found" 2024-11-06 21:11:32,605 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 21:11:33,409 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-06 21:11:33,409 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:33,409 DEBUG --- stdout --- 2024-11-06 21:11:33,409 DEBUG /results/orders/benchmark-10/rc.txt file found 2024-11-06 21:11:33,409 DEBUG --- stderr --- 2024-11-06 21:11:33,409 DEBUG 2024-11-06 21:11:33,409 INFO 2024-11-06 21:11:33,409 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:33,409 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:33,686 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:33,687 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:33,687 DEBUG --- stdout --- 2024-11-06 21:11:33,687 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:33,687 DEBUG --- stderr --- 2024-11-06 21:11:33,687 DEBUG 2024-11-06 21:11:33,687 INFO 2024-11-06 21:11:33,687 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-10/rc.txt 2024-11-06 21:11:34,489 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-06 21:11:34,489 DEBUG --- stdout --- 2024-11-06 21:11:34,489 DEBUG 4 /results/orders/benchmark-10/rc.txt 2024-11-06 21:11:34,489 DEBUG --- stderr --- 2024-11-06 21:11:34,489 DEBUG 2024-11-06 21:11:34,489 INFO - Download file (size 4k) 2024-11-06 21:11:34,490 INFO 2024-11-06 21:11:34,490 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:34,490 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:34,767 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:34,768 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:34,768 DEBUG --- stdout --- 2024-11-06 21:11:34,768 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:34,768 DEBUG --- stderr --- 2024-11-06 21:11:34,768 DEBUG source : /results/orders/benchmark-10/rc.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/rc.txt 2024-11-06 21:11:34,768 INFO 2024-11-06 21:11:34,768 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:34,768 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:35,048 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:35,048 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:35,048 DEBUG --- stdout --- 2024-11-06 21:11:35,048 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:35,048 DEBUG --- stderr --- 2024-11-06 21:11:35,048 DEBUG 2024-11-06 21:11:35,049 INFO 2024-11-06 21:11:35,049 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-10/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/rc.txt 2024-11-06 21:11:35,049 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-06 21:11:35,809 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:35,809 DEBUG --- stdout --- 2024-11-06 21:11:35,809 DEBUG tar: Removing leading `/' from member names 2024-11-06 21:11:35,809 DEBUG --- stderr --- 2024-11-06 21:11:35,809 DEBUG 2024-11-06 21:11:35,810 INFO 2024-11-06 21:11:35,810 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:35,810 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:36,087 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:36,087 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:36,087 DEBUG --- stdout --- 2024-11-06 21:11:36,087 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:36,087 DEBUG --- stderr --- 2024-11-06 21:11:36,087 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-06 21:11:36,087 INFO 2024-11-06 21:11:36,087 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:36,087 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:36,363 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:36,364 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:36,364 DEBUG --- stdout --- 2024-11-06 21:11:36,364 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:36,364 DEBUG --- stderr --- 2024-11-06 21:11:36,364 DEBUG 2024-11-06 21:11:36,364 INFO 2024-11-06 21:11:36,365 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-10/stderr.txt" ]; then echo "/results/orders/benchmark-10/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-10/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-10/stderr.txt file found" 2024-11-06 21:11:36,365 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 21:11:37,154 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-06 21:11:37,154 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:37,155 DEBUG --- stdout --- 2024-11-06 21:11:37,155 DEBUG /results/orders/benchmark-10/stderr.txt file found 2024-11-06 21:11:37,155 DEBUG --- stderr --- 2024-11-06 21:11:37,155 DEBUG 2024-11-06 21:11:37,156 INFO 2024-11-06 21:11:37,156 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:37,156 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:37,433 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:37,433 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:37,433 DEBUG --- stdout --- 2024-11-06 21:11:37,433 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:37,433 DEBUG --- stderr --- 2024-11-06 21:11:37,433 DEBUG 2024-11-06 21:11:37,433 INFO 2024-11-06 21:11:37,434 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-10/stderr.txt 2024-11-06 21:11:38,240 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-06 21:11:38,240 DEBUG --- stdout --- 2024-11-06 21:11:38,240 DEBUG 0 /results/orders/benchmark-10/stderr.txt 2024-11-06 21:11:38,240 DEBUG --- stderr --- 2024-11-06 21:11:38,240 DEBUG 2024-11-06 21:11:38,240 INFO - Download file (size 0k) 2024-11-06 21:11:38,241 INFO 2024-11-06 21:11:38,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-06 21:11:38,241 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:38,516 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:38,516 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:38,516 DEBUG --- stdout --- 2024-11-06 21:11:38,517 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:38,517 DEBUG --- stderr --- 2024-11-06 21:11:38,517 DEBUG source : /results/orders/benchmark-10/stderr.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stderr.txt 2024-11-06 21:11:38,517 INFO 2024-11-06 21:11:38,517 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:38,517 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:38,792 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:38,792 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:38,792 DEBUG --- stdout --- 2024-11-06 21:11:38,792 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:38,792 DEBUG --- stderr --- 2024-11-06 21:11:38,792 DEBUG 2024-11-06 21:11:38,793 INFO 2024-11-06 21:11:38,793 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-10/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stderr.txt 2024-11-06 21:11:38,793 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-06 21:11:39,548 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:39,548 DEBUG --- stdout --- 2024-11-06 21:11:39,548 DEBUG tar: Removing leading `/' from member names 2024-11-06 21:11:39,548 DEBUG --- stderr --- 2024-11-06 21:11:39,548 DEBUG 2024-11-06 21:11:39,549 INFO 2024-11-06 21:11:39,549 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:39,549 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:39,826 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:39,826 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:39,826 DEBUG --- stdout --- 2024-11-06 21:11:39,826 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:39,826 DEBUG --- stderr --- 2024-11-06 21:11:39,826 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-06 21:11:39,826 INFO 2024-11-06 21:11:39,826 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:39,826 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:40,137 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:40,137 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:40,137 DEBUG --- stdout --- 2024-11-06 21:11:40,138 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:40,138 DEBUG --- stderr --- 2024-11-06 21:11:40,138 DEBUG 2024-11-06 21:11:40,138 INFO 2024-11-06 21:11:40,138 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-10/stdout.txt" ]; then echo "/results/orders/benchmark-10/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-10/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-10/stdout.txt file found" 2024-11-06 21:11:40,138 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 21:11:40,933 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-06 21:11:40,934 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:40,934 DEBUG --- stdout --- 2024-11-06 21:11:40,934 DEBUG /results/orders/benchmark-10/stdout.txt file found 2024-11-06 21:11:40,934 DEBUG --- stderr --- 2024-11-06 21:11:40,934 DEBUG 2024-11-06 21:11:40,934 INFO 2024-11-06 21:11:40,934 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:40,934 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:41,255 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:41,255 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:41,255 DEBUG --- stdout --- 2024-11-06 21:11:41,255 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:41,255 DEBUG --- stderr --- 2024-11-06 21:11:41,255 DEBUG 2024-11-06 21:11:41,255 INFO 2024-11-06 21:11:41,255 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-10/stdout.txt 2024-11-06 21:11:42,086 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-06 21:11:42,086 DEBUG --- stdout --- 2024-11-06 21:11:42,086 DEBUG 3732 /results/orders/benchmark-10/stdout.txt 2024-11-06 21:11:42,086 DEBUG --- stderr --- 2024-11-06 21:11:42,086 DEBUG 2024-11-06 21:11:42,086 INFO - Download file (size 3732k) 2024-11-06 21:11:42,086 INFO 2024-11-06 21:11:42,086 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:42,087 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:42,363 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:42,363 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:42,363 DEBUG --- stdout --- 2024-11-06 21:11:42,364 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:42,364 DEBUG --- stderr --- 2024-11-06 21:11:42,364 DEBUG source : /results/orders/benchmark-10/stdout.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stdout.txt 2024-11-06 21:11:42,364 INFO 2024-11-06 21:11:42,364 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:42,364 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:42,640 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:42,640 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:42,640 DEBUG --- stdout --- 2024-11-06 21:11:42,640 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:42,640 DEBUG --- stderr --- 2024-11-06 21:11:42,640 DEBUG 2024-11-06 21:11:42,640 INFO 2024-11-06 21:11:42,640 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-10/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stdout.txt 2024-11-06 21:11:42,640 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-06 21:11:43,869 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:43,869 DEBUG --- stdout --- 2024-11-06 21:11:43,869 DEBUG tar: Removing leading `/' from member names 2024-11-06 21:11:43,869 DEBUG --- stderr --- 2024-11-06 21:11:43,869 DEBUG ________________________________________________________________________________ [2024-11-06 21:11:43] benchmark-10 show_cmd : Display order command ________________________________________________________________________________ 2024-11-06 21:11:43,869 INFO 2024-11-06 21:11:43,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-06 21:11:43,869 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:44,148 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:44,148 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:44,148 DEBUG --- stdout --- 2024-11-06 21:11:44,148 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:44,148 DEBUG --- stderr --- 2024-11-06 21:11:44,148 DEBUG Checking pod: overseer-0-6644bb6697-9gfkx for file exists, if not wait. 2024-11-06 21:11:44,148 INFO 2024-11-06 21:11:44,148 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:44,148 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:44,425 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:44,425 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:44,425 DEBUG --- stdout --- 2024-11-06 21:11:44,425 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:44,425 DEBUG --- stderr --- 2024-11-06 21:11:44,425 DEBUG 2024-11-06 21:11:44,426 INFO 2024-11-06 21:11:44,426 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- bash -c 'if [ -f "/results/orders/benchmark-10/cmd.txt" ]; then echo "/results/orders/benchmark-10/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-10/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-10/cmd.txt file found" 2024-11-06 21:11:44,426 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-06 21:11:45,224 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-06 21:11:45,224 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:45,224 DEBUG --- stdout --- 2024-11-06 21:11:45,224 DEBUG /results/orders/benchmark-10/cmd.txt file found 2024-11-06 21:11:45,224 DEBUG --- stderr --- 2024-11-06 21:11:45,224 DEBUG 2024-11-06 21:11:45,224 INFO 2024-11-06 21:11:45,224 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:45,224 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:45,499 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:45,499 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:45,499 DEBUG --- stdout --- 2024-11-06 21:11:45,499 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:45,499 DEBUG --- stderr --- 2024-11-06 21:11:45,499 DEBUG 2024-11-06 21:11:45,499 INFO 2024-11-06 21:11:45,499 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-9gfkx --stdin -- du -k /results/orders/benchmark-10/cmd.txt 2024-11-06 21:11:46,291 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-06 21:11:46,291 DEBUG --- stdout --- 2024-11-06 21:11:46,291 DEBUG 4 /results/orders/benchmark-10/cmd.txt 2024-11-06 21:11:46,291 DEBUG --- stderr --- 2024-11-06 21:11:46,291 DEBUG 2024-11-06 21:11:46,291 INFO - Download file (size 4k) 2024-11-06 21:11:46,292 INFO 2024-11-06 21:11:46,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-06 21:11:46,292 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:46,568 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:46,568 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:46,568 DEBUG --- stdout --- 2024-11-06 21:11:46,568 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:46,568 DEBUG --- stderr --- 2024-11-06 21:11:46,568 DEBUG source : /results/orders/benchmark-10/cmd.txt @ overseer-0-6644bb6697-9gfkx destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/cmd.txt 2024-11-06 21:11:46,569 INFO 2024-11-06 21:11:46,569 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-06 21:11:46,569 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-06 21:11:46,869 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-06 21:11:46,870 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:46,870 DEBUG --- stdout --- 2024-11-06 21:11:46,870 DEBUG overseer-0-6644bb6697-9gfkx 2024-11-06 21:11:46,870 DEBUG --- stderr --- 2024-11-06 21:11:46,870 DEBUG 2024-11-06 21:11:46,870 INFO 2024-11-06 21:11:46,870 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-9gfkx:/results/orders/benchmark-10/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/cmd.txt 2024-11-06 21:11:46,870 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-06 21:11:47,628 INFO [loop_until]: OK (rc = 0) 2024-11-06 21:11:47,628 DEBUG --- stdout --- 2024-11-06 21:11:47,628 DEBUG tar: Removing leading `/' from member names 2024-11-06 21:11:47,628 DEBUG --- stderr --- 2024-11-06 21:11:47,628 DEBUG 2024-11-06 21:11:47,628 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/cmd.txt is small (1 lines), printing all lines: 2024-11-06 21:11:47,628 DEBUG ----- output ----- 2024-11-06 21:11:47,628 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-10" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd" 2024-11-06 21:11:47,629 INFO ________________________________________________________________________________ [2024-11-06 21:11:47] benchmark-10 show_rc : Display rc order ________________________________________________________________________________ 2024-11-06 21:11:47,629 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/rc.txt is small (1 lines), printing all lines: 2024-11-06 21:11:47,629 DEBUG ----- output ----- 2024-11-06 21:11:47,629 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-06 21:11:47,629 INFO ________________________________________________________________________________ [2024-11-06 21:11:47] benchmark-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-06 21:11:47,635 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/01_benchmark-10/stdout.txt is 50364 lines): 2024-11-06 21:11:47,635 DEBUG ----- output ----- 2024-11-06 21:11:47,635 INFO 2024-11-06 20:11:09,372 DEBUG Creating results folder /results/gatling/benchmark-10 2024-11-06 21:11:47,635 INFO 2024-11-06 20:11:09,380 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp6vrc9hsu 2024-11-06 21:11:47,635 INFO 2024-11-06 20:11:09,381 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-10" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=10 -Duser_password=**** 2024-11-06 21:11:47,635 INFO [INFO] Scanning for projects... 2024-11-06 21:11:47,635 INFO [INFO] 2024-11-06 21:11:47,635 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-06 21:11:47,635 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-06 21:11:47,635 INFO [INFO] from pom.xml 2024-11-06 21:11:47,635 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-06 21:11:47,635 INFO [INFO] 2024-11-06 21:11:47,635 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-06 21:11:47,635 INFO [INFO] 2024-11-06 21:11:47,635 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-06 21:11:47,635 INFO [INFO] skip non existing resourceDirectory /tmp/tmp6vrc9hsu/src/main/resources 2024-11-06 21:11:47,635 INFO [INFO] 2024-11-06 21:11:47,635 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-06 21:11:47,635 INFO [INFO] No sources to compile 2024-11-06 21:11:47,635 INFO [INFO] 2024-11-06 21:11:47,635 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-06 21:11:47,635 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-06 21:11:47,635 INFO [INFO] 2024-11-06 21:11:47,635 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-06 21:11:47,635 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-06 21:11:47,635 INFO [INFO] 2024-11-06 21:11:47,635 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-06 21:11:47,635 INFO [INFO] compiling 38 Scala sources to /tmp/tmp6vrc9hsu/target/test-classes ... 2024-11-06 21:11:47,635 INFO [INFO] compile in 6.8 s 2024-11-06 21:11:47,635 INFO [INFO] 2024-11-06 21:11:47,635 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-06 21:11:47,635 INFO [INFO] 2024-11-06 21:11:47,635 INFO [INFO] 2024-11-06 21:11:47,635 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-06 21:11:47,635 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-06 21:11:47,635 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-06 21:11:47,635 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-06 21:11:47,635 INFO 2024-11-06 21:11:47,635 INFO ================================================================================ 2024-11-06 21:11:47,635 INFO 2024-11-06 20:11:25 GMT 5s elapsed 2024-11-06 21:11:47,635 INFO ---- Requests ------------------------------------------------------------------ 2024-11-06 21:11:47,635 INFO > Global (OK=61 KO=0 ) 2024-11-06 21:11:47,635 INFO > restLoginInitiate (OK=3 KO=0 ) 2024-11-06 21:11:47,635 INFO > restLoginUsernamePasswordCallback (OK=3 KO=0 ) 2024-11-06 21:11:47,635 INFO > UI-Post-Login - stage 1 (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI AM idFromSession (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI AM validateGoto (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > User Authorize PKCE stage (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > User AccessToken PKCE stage (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Post-Login - stage 2 (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI Enduser appAuthHelper2 (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI Enduser appAuthHelper1 (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > loginTreeWorkflow_restLoginInitiate (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Post-Login - stage 3 (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI Enduser sessioncheck (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI IDM uiconfig (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI IDM version (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI IDM features (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI IDM privilege (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI IDM login (OK=2 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI IDM dashboard (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI IDM managed user schema (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI IDM user (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI IDM oidcToken (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > userinfo (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Logout (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Logout Redirect 1 (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI AM revoke1 (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI AM revoke2 (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI AM endsession (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > Skip 2FA (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO 2024-11-06 21:11:47,636 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-06 21:11:47,636 INFO [- ] 0% 2024-11-06 21:11:47,636 INFO waiting: 36108 / active: 2 / done: 2 2024-11-06 21:11:47,636 INFO ================================================================================ 2024-11-06 21:11:47,636 INFO 2024-11-06 21:11:47,636 INFO 2024-11-06 21:11:47,636 INFO ================================================================================ 2024-11-06 21:11:47,636 INFO 2024-11-06 20:11:30 GMT 10s elapsed 2024-11-06 21:11:47,636 INFO ---- Requests ------------------------------------------------------------------ 2024-11-06 21:11:47,636 INFO > Global (OK=233 KO=0 ) 2024-11-06 21:11:47,636 INFO > restLoginInitiate (OK=15 KO=0 ) 2024-11-06 21:11:47,636 INFO > restLoginUsernamePasswordCallback (OK=15 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Post-Login - stage 1 (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI AM idFromSession (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI AM validateGoto (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > User Authorize PKCE stage (OK=20 KO=0 ) 2024-11-06 21:11:47,636 INFO > User AccessToken PKCE stage (OK=19 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Post-Login - stage 2 (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI Enduser appAuthHelper2 (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI Enduser appAuthHelper1 (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > loginTreeWorkflow_restLoginInitiate (OK=1 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Post-Login - stage 3 (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO > UI Enduser sessioncheck (OK=5 KO=0 ) 2024-11-06 21:11:47,636 INFO [...] 2024-11-06 21:11:47,646 INFO > Global (OK=501356 KO=19 ) 2024-11-06 21:11:47,646 INFO > restLoginInitiate (OK=34569 KO=1 ) 2024-11-06 21:11:47,646 INFO > restLoginUsernamePasswordCallback (OK=34564 KO=1 ) 2024-11-06 21:11:47,646 INFO > UI-Post-Login - stage 1 (OK=7660 KO=0 ) 2024-11-06 21:11:47,646 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=7660 KO=0 ) 2024-11-06 21:11:47,646 INFO > UI AM idFromSession (OK=7659 KO=1 ) 2024-11-06 21:11:47,646 INFO > UI AM validateGoto (OK=7659 KO=1 ) 2024-11-06 21:11:47,646 INFO > User Authorize PKCE stage (OK=42219 KO=3 ) 2024-11-06 21:11:47,646 INFO > User AccessToken PKCE stage (OK=42216 KO=4 ) 2024-11-06 21:11:47,646 INFO > UI-Post-Login - stage 2 (OK=7660 KO=0 ) 2024-11-06 21:11:47,646 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=7660 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI Enduser appAuthHelper2 (OK=7660 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI Enduser appAuthHelper1 (OK=7660 KO=0 ) 2024-11-06 21:11:47,647 INFO > loginTreeWorkflow_restLoginInitiate (OK=1225 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI-Post-Login - stage 3 (OK=7657 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=7657 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI Enduser sessioncheck (OK=7657 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI IDM uiconfig (OK=7657 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI IDM version (OK=7657 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI IDM features (OK=7657 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI IDM privilege (OK=7657 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI IDM login (OK=7656 KO=1 ) 2024-11-06 21:11:47,647 INFO > UI IDM dashboard (OK=7656 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI IDM managed user schema (OK=7656 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI IDM user (OK=7656 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI IDM oidcToken (OK=7658 KO=1 ) 2024-11-06 21:11:47,647 INFO > userinfo (OK=7657 KO=0 ) 2024-11-06 21:11:47,647 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1225 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI-Logout (OK=7659 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI-Logout Redirect 1 (OK=7659 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI AM revoke1 (OK=7657 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI AM revoke2 (OK=7656 KO=0 ) 2024-11-06 21:11:47,647 INFO > UI AM endsession (OK=7658 KO=0 ) 2024-11-06 21:11:47,647 INFO > Skip 2FA (OK=19252 KO=0 ) 2024-11-06 21:11:47,647 INFO > RootUserinfo (OK=25670 KO=0 ) 2024-11-06 21:11:47,647 INFO > GetUUid (OK=11577 KO=0 ) 2024-11-06 21:11:47,647 INFO > GetOpenidConfig (OK=34695 KO=0 ) 2024-11-06 21:11:47,647 INFO > CreatePolicy (OK=11575 KO=2 ) 2024-11-06 21:11:47,647 INFO > tokenUserinfo (OK=7651 KO=0 ) 2024-11-06 21:11:47,647 INFO > getIdToken (OK=2540 KO=0 ) 2024-11-06 21:11:47,647 INFO > getIdToken Redirect 1 (OK=2540 KO=0 ) 2024-11-06 21:11:47,647 INFO > createOrganization (OK=2540 KO=0 ) 2024-11-06 21:11:47,647 INFO > queryOrganization (OK=2539 KO=0 ) 2024-11-06 21:11:47,647 INFO > deleteOrganization (OK=2539 KO=0 ) 2024-11-06 21:11:47,647 INFO > ReadPolicy (OK=11564 KO=2 ) 2024-11-06 21:11:47,647 INFO > DeletePolicy (OK=11550 KO=2 ) 2024-11-06 21:11:47,647 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-11-06 21:11:47,647 INFO ---- Errors -------------------------------------------------------------------- 2024-11-06 21:11:47,647 INFO > status.find.in([200, 209], 304), found 502 6 (20.69%) 2024-11-06 21:11:47,647 INFO > status.find.in([200, 209], 304), found 404 4 (13.79%) 2024-11-06 21:11:47,647 INFO > status.find.is(302), but actually found 502 3 (10.34%) 2024-11-06 21:11:47,647 INFO > UI AM revoke2: No attribute named 'access_token_2' is defined 3 (10.34%) 2024-11-06 21:11:47,647 INFO 2024-11-06 21:11:47,647 INFO > tokenUserinfo: No attribute named 'access_token' is defined 2 ( 6.90%) 2024-11-06 21:11:47,647 INFO > status.find.is(200), but actually found 502 2 ( 6.90%) 2024-11-06 21:11:47,647 INFO > userinfo: No attribute named 'access_token' is defined 2 ( 6.90%) 2024-11-06 21:11:47,647 INFO > UI AM revoke1: No attribute named 'access_token' is defined 2 ( 6.90%) 2024-11-06 21:11:47,647 INFO > status.find.in([200, 209], 304), found 409 1 ( 3.45%) 2024-11-06 21:11:47,647 INFO > status.find.in([200, 209], 304), found 401 1 ( 3.45%) 2024-11-06 21:11:47,647 INFO > headerRegex((location,#id_token=(.*))).find.exists, found noth 1 ( 3.45%) 2024-11-06 21:11:47,647 INFO ing 2024-11-06 21:11:47,647 INFO > UI AM endsession: No attribute named 'id_token_2' is defined 1 ( 3.45%) 2024-11-06 21:11:47,647 INFO > status.find.in([200, 209], 304), found 400 1 ( 3.45%) 2024-11-06 21:11:47,647 INFO 2024-11-06 21:11:47,647 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-06 21:11:47,647 INFO [#########################################################################-] 99% 2024-11-06 21:11:47,647 INFO waiting: 316 / active: 36 / done: 35760 2024-11-06 21:11:47,647 INFO ================================================================================ 2024-11-06 21:11:47,647 INFO 2024-11-06 21:11:47,647 INFO 21:11:20.240 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'restLoginInitiate' failed for user 35796: j.i.IOException: Premature close 2024-11-06 21:11:47,648 INFO 21:11:20.241 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-06 21:11:47,648 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-06 21:11:47,648 INFO Request: 2024-11-06 21:11:47,648 INFO restLoginInitiate: KO j.i.IOException: Premature close 2024-11-06 21:11:47,648 INFO ========================= 2024-11-06 21:11:47,648 INFO Session: 2024-11-06 21:11:47,648 INFO Session(idc.benchmark.Mix2024,35796,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, username -> user.336866, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@56da65c7, fake-user-agent -> Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:60.0) Gecko/20100101 Firefox/60.0, password -> Pa_ssw0rd, fake-ip -> 244.49.252.55, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@42c5907c),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007e50ac698000@f078bd1,io.netty.channel.epoll.EpollEventLoop@239b0f9d) 2024-11-06 21:11:47,648 INFO ========================= 2024-11-06 21:11:47,648 INFO HTTP request: 2024-11-06 21:11:47,648 INFO POST https://openam-perf-benchmark.forgeblocks.com/am/json/realms/root/authenticate 2024-11-06 21:11:47,648 INFO headers: 2024-11-06 21:11:47,648 INFO accept: application/json 2024-11-06 21:11:47,648 INFO content-type: application/json 2024-11-06 21:11:47,648 INFO user-agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:60.0) Gecko/20100101 Firefox/60.0 2024-11-06 21:11:47,648 INFO X-Forwarded-For: 244.49.252.55 2024-11-06 21:11:47,648 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-06 21:11:47,648 INFO content-length: 0 2024-11-06 21:11:47,648 INFO body:StringChunksRequestBody{charset=UTF-8, content=} 2024-11-06 21:11:47,648 INFO ========================= 2024-11-06 21:11:47,648 INFO HTTP response: 2024-11-06 21:11:47,648 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-06 21:11:47,648 INFO 21:11:20.241 [ERROR] i.g.c.a.b.SessionHookBuilder$$anon$1 - 'hook-133' crashed with 'j.u.NoSuchElementException: No attribute named 'callbacks' is defined', forwarding to the next one 2024-11-06 21:11:47,648 INFO [INFO] ------------------------------------------------------------------------ 2024-11-06 21:11:47,648 INFO [INFO] BUILD SUCCESS 2024-11-06 21:11:47,648 INFO [INFO] ------------------------------------------------------------------------ 2024-11-06 21:11:47,648 INFO [INFO] Total time: 01:00 h 2024-11-06 21:11:47,648 INFO [INFO] Finished at: 2024-11-06T21:11:20Z 2024-11-06 21:11:47,648 INFO [INFO] ------------------------------------------------------------------------ 2024-11-06 21:11:47,648 INFO 2024-11-06 21:11:20,326 INFO Gatling simulation run ended successfully 2024-11-06 21:11:47,648 INFO 2024-11-06 21:11:20,326 DEBUG No archive name provided. No archive will be created 2024-11-06 21:11:47,648 INFO ________________________________________________________________________________ [2024-11-06 21:11:47] benchmark-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-06 21:11:47,648 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stderr.txt is small (0 lines), printing all lines: 2024-11-06 21:11:47,648 DEBUG ----- output ----- 2024-11-06 21:11:47,648 DEBUG 2024-11-06 21:11:47,648 INFO ________________________________________________________________________________ [2024-11-06 21:11:47] benchmark-10 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-06 21:11:47] benchmark-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-06 21:11:47] benchmark-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped