--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-13 05:34:36] benchmark-10 pre : N/A ________________________________________________________________________________ 2024-11-13 05:34:36 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-13 05:34:36 - 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-13 05:34:37,467 INFO 2024-11-13 05:34:37,468 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-13 05:34:37,468 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 05:34:37,780 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 05:34:37,780 INFO [loop_until]: OK (rc = 0) 2024-11-13 05:34:37,780 DEBUG --- stdout --- 2024-11-13 05:34:37,780 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 05:34:37,780 DEBUG --- stderr --- 2024-11-13 05:34:37,780 DEBUG 2024-11-13 05:34:37 - INFO: Get overseer pod resources requests memory 2024-11-13 05:34:37,780 INFO 2024-11-13 05:34:37,780 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-6644bb6697-rchxr --output jsonpath={.spec.containers[].resources.requests.memory} 2024-11-13 05:34:37,780 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 05:34:38,082 INFO [loop_until]: OK (rc = 0) 2024-11-13 05:34:38,082 DEBUG --- stdout --- 2024-11-13 05:34:38,082 DEBUG 4Gi 2024-11-13 05:34:38,082 DEBUG --- stderr --- 2024-11-13 05:34:38,082 DEBUG ________________________________________________________________________________ [2024-11-13 05:34:38] benchmark-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-13 05:34:38,082 INFO 2024-11-13 05:34:38,082 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-13 05:34:38,082 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 05:34:38,366 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 05:34:38,366 INFO [loop_until]: OK (rc = 0) 2024-11-13 05:34:38,366 DEBUG --- stdout --- 2024-11-13 05:34:38,366 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 05:34:38,366 DEBUG --- stderr --- 2024-11-13 05:34:38,366 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=200000 -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-13 05:34:38] benchmark-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-13 05:34:38,367 INFO 2024-11-13 05:34:38,367 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-13 05:34:38,367 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 05:34:38,649 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 05:34:38,649 INFO [loop_until]: OK (rc = 0) 2024-11-13 05:34:38,650 DEBUG --- stdout --- 2024-11-13 05:34:38,650 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 05:34:38,650 DEBUG --- stderr --- 2024-11-13 05:34:38,650 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=200000 -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-13 05:34:38] 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-13 05:34:38,652 INFO 2024-11-13 05:34:38,653 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-13 05:34:38,653 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-13 05:34:38,970 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 05:34:38,970 INFO [loop_until]: OK (rc = 0) 2024-11-13 05:34:38,970 DEBUG --- stdout --- 2024-11-13 05:34:38,970 DEBUG WAITING 2024-11-13 05:34:38,970 DEBUG --- stderr --- 2024-11-13 05:34:38,970 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 22 0 --:--:-- --:--:-- --:--:-- 22 ________________________________________________________________________________ [2024-11-13 05:34:38] benchmark-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-13 05:34:38,971 INFO 2024-11-13 05:34:38,971 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-13 05:34:38,971 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 05:34:39,256 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 05:34:39,256 INFO [loop_until]: OK (rc = 0) 2024-11-13 05:34:39,256 DEBUG --- stdout --- 2024-11-13 05:34:39,256 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 05:34:39,256 DEBUG --- stderr --- 2024-11-13 05:34:39,256 DEBUG 2024-11-13 05:34:39,257 INFO 2024-11-13 05:34:39,257 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-rchxr:/results/orders/order.json 2024-11-13 05:34:39,257 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 05:34:40,698 INFO [loop_until]: OK (rc = 0) 2024-11-13 05:34:40,698 DEBUG --- stdout --- 2024-11-13 05:34:40,698 DEBUG 2024-11-13 05:34:40,698 DEBUG --- stderr --- 2024-11-13 05:34:40,698 DEBUG Order has been successfully forwarded to overseer 2024-11-13 05:34:40 - INFO: Check if Gatling simulation started successfully 2024-11-13 05:34:40,698 INFO 2024-11-13 05:34:40,698 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-13 05:34:40,698 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 05:34:40,980 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 05:34:40,980 INFO [loop_until]: OK (rc = 0) 2024-11-13 05:34:40,980 DEBUG --- stdout --- 2024-11-13 05:34:40,980 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 05:34:40,980 DEBUG --- stderr --- 2024-11-13 05:34:40,981 DEBUG 2024-11-13 05:34:40,981 INFO 2024-11-13 05:34:40,981 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-10/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-13 05:34:40,981 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-13 05:34:41,730 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-13 05:34:47,491 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-13 05:34:53,244 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-13 05:34:53,244 INFO [loop_until]: OK (rc = 0) 2024-11-13 05:34:53,244 DEBUG --- stdout --- 2024-11-13 05:34:53,244 DEBUG 2024-11-13 05:34:41,435 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpg68iq1_m 2024-11-13 05:34:41,437 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=200000 -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/tmpg68iq1_m/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/tmpg68iq1_m/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-13 05:34:53,244 DEBUG --- stderr --- 2024-11-13 05:34:53,244 DEBUG ________________________________________________________________________________ [2024-11-13 05:34:53] 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 04s, 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 07s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 08s, retry Order is running since 58m 09s, retry PASS : Order stopped by itself after 1h and before timeout (1h 12m) ________________________________________________________________________________ [2024-11-13 06:35:02] benchmark-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-13 06:35:02,698 INFO 2024-11-13 06:35:02,698 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-13 06:35:02,698 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:03,011 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:03,011 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:03,011 DEBUG --- stdout --- 2024-11-13 06:35:03,011 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:03,011 DEBUG --- stderr --- 2024-11-13 06:35:03,011 DEBUG 2024-11-13 06:35:03,011 INFO 2024-11-13 06:35:03,011 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-13 06:35:03,011 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 06:35:03,811 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:03,811 DEBUG --- stdout --- 2024-11-13 06:35:03,811 DEBUG 2024-11-13 06:35:03,811 DEBUG --- stderr --- 2024-11-13 06:35:03,811 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-13 06:35:03] benchmark-10 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-13 06:35:03 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/order.json 2024-11-13 06:35:03,811 INFO 2024-11-13 06:35:03,811 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-13 06:35:03,811 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:04,098 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:04,098 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:04,098 DEBUG --- stdout --- 2024-11-13 06:35:04,098 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:04,098 DEBUG --- stderr --- 2024-11-13 06:35:04,098 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 06:35:04,099 INFO 2024-11-13 06:35:04,099 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-13 06:35:04,099 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:04,383 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:04,383 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:04,383 DEBUG --- stdout --- 2024-11-13 06:35:04,383 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:04,383 DEBUG --- stderr --- 2024-11-13 06:35:04,383 DEBUG 2024-11-13 06:35:04,384 INFO 2024-11-13 06:35:04,384 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-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-13 06:35:04,384 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 06:35:05,207 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 06:35:05,207 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:05,207 DEBUG --- stdout --- 2024-11-13 06:35:05,207 DEBUG /results/orders/benchmark-10/rc.txt file found 2024-11-13 06:35:05,207 DEBUG --- stderr --- 2024-11-13 06:35:05,207 DEBUG 2024-11-13 06:35:05,207 INFO 2024-11-13 06:35:05,207 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-13 06:35:05,207 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:05,493 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:05,493 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:05,493 DEBUG --- stdout --- 2024-11-13 06:35:05,494 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:05,494 DEBUG --- stderr --- 2024-11-13 06:35:05,494 DEBUG 2024-11-13 06:35:05,494 INFO 2024-11-13 06:35:05,494 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-10/rc.txt 2024-11-13 06:35:06,320 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 06:35:06,320 DEBUG --- stdout --- 2024-11-13 06:35:06,320 DEBUG 4 /results/orders/benchmark-10/rc.txt 2024-11-13 06:35:06,320 DEBUG --- stderr --- 2024-11-13 06:35:06,320 DEBUG 2024-11-13 06:35:06,320 INFO - Download file (size 4k) 2024-11-13 06:35:06,320 INFO 2024-11-13 06:35:06,320 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-13 06:35:06,320 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:06,605 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:06,605 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:06,605 DEBUG --- stdout --- 2024-11-13 06:35:06,605 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:06,605 DEBUG --- stderr --- 2024-11-13 06:35:06,605 DEBUG source : /results/orders/benchmark-10/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/rc.txt 2024-11-13 06:35:06,605 INFO 2024-11-13 06:35:06,605 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-13 06:35:06,606 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:06,891 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:06,892 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:06,892 DEBUG --- stdout --- 2024-11-13 06:35:06,892 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:06,892 DEBUG --- stderr --- 2024-11-13 06:35:06,892 DEBUG 2024-11-13 06:35:06,892 INFO 2024-11-13 06:35:06,892 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/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-13 06:35:06,892 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 06:35:07,673 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:07,673 DEBUG --- stdout --- 2024-11-13 06:35:07,673 DEBUG tar: Removing leading `/' from member names 2024-11-13 06:35:07,673 DEBUG --- stderr --- 2024-11-13 06:35:07,673 DEBUG 2024-11-13 06:35:07,673 INFO 2024-11-13 06:35:07,674 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-13 06:35:07,674 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:07,957 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:07,957 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:07,957 DEBUG --- stdout --- 2024-11-13 06:35:07,957 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:07,957 DEBUG --- stderr --- 2024-11-13 06:35:07,957 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 06:35:07,957 INFO 2024-11-13 06:35:07,957 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-13 06:35:07,957 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:08,241 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:08,241 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:08,241 DEBUG --- stdout --- 2024-11-13 06:35:08,241 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:08,242 DEBUG --- stderr --- 2024-11-13 06:35:08,242 DEBUG 2024-11-13 06:35:08,242 INFO 2024-11-13 06:35:08,242 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-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-13 06:35:08,242 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 06:35:09,128 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 06:35:09,128 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:09,128 DEBUG --- stdout --- 2024-11-13 06:35:09,128 DEBUG /results/orders/benchmark-10/stderr.txt file found 2024-11-13 06:35:09,128 DEBUG --- stderr --- 2024-11-13 06:35:09,128 DEBUG 2024-11-13 06:35:09,128 INFO 2024-11-13 06:35:09,128 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-13 06:35:09,128 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:09,413 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:09,413 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:09,413 DEBUG --- stdout --- 2024-11-13 06:35:09,413 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:09,414 DEBUG --- stderr --- 2024-11-13 06:35:09,414 DEBUG 2024-11-13 06:35:09,414 INFO 2024-11-13 06:35:09,414 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-10/stderr.txt 2024-11-13 06:35:10,264 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 06:35:10,264 DEBUG --- stdout --- 2024-11-13 06:35:10,265 DEBUG 0 /results/orders/benchmark-10/stderr.txt 2024-11-13 06:35:10,265 DEBUG --- stderr --- 2024-11-13 06:35:10,265 DEBUG 2024-11-13 06:35:10,265 INFO - Download file (size 0k) 2024-11-13 06:35:10,265 INFO 2024-11-13 06:35:10,265 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-13 06:35:10,265 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:10,550 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:10,550 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:10,550 DEBUG --- stdout --- 2024-11-13 06:35:10,550 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:10,550 DEBUG --- stderr --- 2024-11-13 06:35:10,550 DEBUG source : /results/orders/benchmark-10/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stderr.txt 2024-11-13 06:35:10,551 INFO 2024-11-13 06:35:10,551 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-13 06:35:10,551 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:10,834 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:10,834 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:10,834 DEBUG --- stdout --- 2024-11-13 06:35:10,834 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:10,834 DEBUG --- stderr --- 2024-11-13 06:35:10,834 DEBUG 2024-11-13 06:35:10,835 INFO 2024-11-13 06:35:10,835 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/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-13 06:35:10,835 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 06:35:11,621 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:11,621 DEBUG --- stdout --- 2024-11-13 06:35:11,621 DEBUG tar: Removing leading `/' from member names 2024-11-13 06:35:11,621 DEBUG --- stderr --- 2024-11-13 06:35:11,621 DEBUG 2024-11-13 06:35:11,621 INFO 2024-11-13 06:35:11,621 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-13 06:35:11,622 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:11,909 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:11,909 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:11,909 DEBUG --- stdout --- 2024-11-13 06:35:11,909 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:11,910 DEBUG --- stderr --- 2024-11-13 06:35:11,910 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 06:35:11,910 INFO 2024-11-13 06:35:11,910 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-13 06:35:11,910 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:12,193 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:12,194 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:12,194 DEBUG --- stdout --- 2024-11-13 06:35:12,194 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:12,194 DEBUG --- stderr --- 2024-11-13 06:35:12,194 DEBUG 2024-11-13 06:35:12,194 INFO 2024-11-13 06:35:12,194 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-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-13 06:35:12,194 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 06:35:13,019 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 06:35:13,019 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:13,019 DEBUG --- stdout --- 2024-11-13 06:35:13,019 DEBUG /results/orders/benchmark-10/stdout.txt file found 2024-11-13 06:35:13,019 DEBUG --- stderr --- 2024-11-13 06:35:13,019 DEBUG 2024-11-13 06:35:13,020 INFO 2024-11-13 06:35:13,020 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-13 06:35:13,020 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:13,307 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:13,307 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:13,307 DEBUG --- stdout --- 2024-11-13 06:35:13,307 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:13,307 DEBUG --- stderr --- 2024-11-13 06:35:13,307 DEBUG 2024-11-13 06:35:13,307 INFO 2024-11-13 06:35:13,308 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-10/stdout.txt 2024-11-13 06:35:14,127 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 06:35:14,127 DEBUG --- stdout --- 2024-11-13 06:35:14,127 DEBUG 3504 /results/orders/benchmark-10/stdout.txt 2024-11-13 06:35:14,127 DEBUG --- stderr --- 2024-11-13 06:35:14,127 DEBUG 2024-11-13 06:35:14,127 INFO - Download file (size 3504k) 2024-11-13 06:35:14,127 INFO 2024-11-13 06:35:14,127 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-13 06:35:14,127 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:14,415 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:14,415 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:14,415 DEBUG --- stdout --- 2024-11-13 06:35:14,415 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:14,415 DEBUG --- stderr --- 2024-11-13 06:35:14,415 DEBUG source : /results/orders/benchmark-10/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stdout.txt 2024-11-13 06:35:14,415 INFO 2024-11-13 06:35:14,415 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-13 06:35:14,415 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:14,700 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:14,700 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:14,700 DEBUG --- stdout --- 2024-11-13 06:35:14,700 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:14,700 DEBUG --- stderr --- 2024-11-13 06:35:14,700 DEBUG 2024-11-13 06:35:14,701 INFO 2024-11-13 06:35:14,701 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/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-13 06:35:14,701 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 06:35:15,965 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:15,965 DEBUG --- stdout --- 2024-11-13 06:35:15,965 DEBUG tar: Removing leading `/' from member names 2024-11-13 06:35:15,965 DEBUG --- stderr --- 2024-11-13 06:35:15,965 DEBUG ________________________________________________________________________________ [2024-11-13 06:35:15] benchmark-10 show_cmd : Display order command ________________________________________________________________________________ 2024-11-13 06:35:15,966 INFO 2024-11-13 06:35:15,966 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-13 06:35:15,966 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:16,254 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:16,254 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:16,254 DEBUG --- stdout --- 2024-11-13 06:35:16,254 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:16,254 DEBUG --- stderr --- 2024-11-13 06:35:16,254 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-13 06:35:16,254 INFO 2024-11-13 06:35:16,254 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-13 06:35:16,254 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:16,541 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:16,541 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:16,541 DEBUG --- stdout --- 2024-11-13 06:35:16,541 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:16,541 DEBUG --- stderr --- 2024-11-13 06:35:16,541 DEBUG 2024-11-13 06:35:16,542 INFO 2024-11-13 06:35:16,542 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-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-13 06:35:16,542 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-13 06:35:17,360 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-13 06:35:17,360 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:17,360 DEBUG --- stdout --- 2024-11-13 06:35:17,360 DEBUG /results/orders/benchmark-10/cmd.txt file found 2024-11-13 06:35:17,360 DEBUG --- stderr --- 2024-11-13 06:35:17,360 DEBUG 2024-11-13 06:35:17,361 INFO 2024-11-13 06:35:17,362 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-13 06:35:17,362 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:17,666 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:17,666 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:17,666 DEBUG --- stdout --- 2024-11-13 06:35:17,666 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:17,666 DEBUG --- stderr --- 2024-11-13 06:35:17,667 DEBUG 2024-11-13 06:35:17,667 INFO 2024-11-13 06:35:17,667 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-10/cmd.txt 2024-11-13 06:35:18,487 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-13 06:35:18,487 DEBUG --- stdout --- 2024-11-13 06:35:18,487 DEBUG 4 /results/orders/benchmark-10/cmd.txt 2024-11-13 06:35:18,487 DEBUG --- stderr --- 2024-11-13 06:35:18,487 DEBUG 2024-11-13 06:35:18,487 INFO - Download file (size 4k) 2024-11-13 06:35:18,488 INFO 2024-11-13 06:35:18,488 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-13 06:35:18,488 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:18,775 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:18,775 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:18,775 DEBUG --- stdout --- 2024-11-13 06:35:18,775 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:18,775 DEBUG --- stderr --- 2024-11-13 06:35:18,775 DEBUG source : /results/orders/benchmark-10/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/cmd.txt 2024-11-13 06:35:18,775 INFO 2024-11-13 06:35:18,775 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-13 06:35:18,775 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-13 06:35:19,062 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-13 06:35:19,062 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:19,062 DEBUG --- stdout --- 2024-11-13 06:35:19,062 DEBUG overseer-0-6644bb6697-rchxr 2024-11-13 06:35:19,062 DEBUG --- stderr --- 2024-11-13 06:35:19,062 DEBUG 2024-11-13 06:35:19,063 INFO 2024-11-13 06:35:19,063 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/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-13 06:35:19,063 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-13 06:35:19,848 INFO [loop_until]: OK (rc = 0) 2024-11-13 06:35:19,848 DEBUG --- stdout --- 2024-11-13 06:35:19,848 DEBUG tar: Removing leading `/' from member names 2024-11-13 06:35:19,848 DEBUG --- stderr --- 2024-11-13 06:35:19,848 DEBUG 2024-11-13 06:35:19,848 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-13 06:35:19,848 DEBUG ----- output ----- 2024-11-13 06:35:19,849 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=200000 -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-13 06:35:19,849 INFO ________________________________________________________________________________ [2024-11-13 06:35:19] benchmark-10 show_rc : Display rc order ________________________________________________________________________________ 2024-11-13 06:35:19,849 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-13 06:35:19,849 DEBUG ----- output ----- 2024-11-13 06:35:19,849 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-13 06:35:19,849 INFO ________________________________________________________________________________ [2024-11-13 06:35:19] benchmark-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-13 06:35:19,854 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 46184 lines): 2024-11-13 06:35:19,854 DEBUG ----- output ----- 2024-11-13 06:35:19,854 INFO 2024-11-13 05:34:41,435 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpg68iq1_m 2024-11-13 06:35:19,854 INFO 2024-11-13 05:34:41,437 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=200000 -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-13 06:35:19,854 INFO [INFO] Scanning for projects... 2024-11-13 06:35:19,854 INFO [INFO] 2024-11-13 06:35:19,854 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-13 06:35:19,854 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-13 06:35:19,854 INFO [INFO] from pom.xml 2024-11-13 06:35:19,854 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-13 06:35:19,854 INFO [INFO] 2024-11-13 06:35:19,854 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-13 06:35:19,854 INFO [INFO] 2024-11-13 06:35:19,854 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-13 06:35:19,854 INFO [INFO] skip non existing resourceDirectory /tmp/tmpg68iq1_m/src/main/resources 2024-11-13 06:35:19,854 INFO [INFO] 2024-11-13 06:35:19,854 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-13 06:35:19,854 INFO [INFO] No sources to compile 2024-11-13 06:35:19,854 INFO [INFO] 2024-11-13 06:35:19,854 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-13 06:35:19,854 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-13 06:35:19,854 INFO [INFO] 2024-11-13 06:35:19,854 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-13 06:35:19,854 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-13 06:35:19,854 INFO [INFO] 2024-11-13 06:35:19,854 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-13 06:35:19,854 INFO [INFO] compiling 38 Scala sources to /tmp/tmpg68iq1_m/target/test-classes ... 2024-11-13 06:35:19,854 INFO [INFO] compile in 6.8 s 2024-11-13 06:35:19,854 INFO [INFO] 2024-11-13 06:35:19,854 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-13 06:35:19,854 INFO [INFO] 2024-11-13 06:35:19,854 INFO [INFO] 2024-11-13 06:35:19,854 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-13 06:35:19,854 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-13 06:35:19,854 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-13 06:35:19,854 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-13 06:35:19,854 INFO 2024-11-13 06:35:19,854 INFO ================================================================================ 2024-11-13 06:35:19,854 INFO 2024-11-13 05:34:57 GMT 5s elapsed 2024-11-13 06:35:19,854 INFO ---- Requests ------------------------------------------------------------------ 2024-11-13 06:35:19,854 INFO > Global (OK=51 KO=0 ) 2024-11-13 06:35:19,854 INFO > restLoginInitiate (OK=4 KO=0 ) 2024-11-13 06:35:19,854 INFO > restLoginUsernamePasswordCallback (OK=4 KO=0 ) 2024-11-13 06:35:19,854 INFO > Skip 2FA (OK=2 KO=0 ) 2024-11-13 06:35:19,854 INFO > User Authorize PKCE stage (OK=5 KO=0 ) 2024-11-13 06:35:19,854 INFO > User AccessToken PKCE stage (OK=5 KO=0 ) 2024-11-13 06:35:19,855 INFO > RootUserinfo (OK=3 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 1 (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI AM idFromSession (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI AM validateGoto (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 2 (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI Enduser appAuthHelper1 (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI Enduser appAuthHelper2 (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 3 (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI Enduser sessioncheck (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI IDM uiconfig (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI IDM version (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI IDM login (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI IDM features (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI IDM privilege (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI IDM managed user schema (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI IDM user (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI IDM dashboard (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI IDM oidcToken (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > userinfo (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Logout (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Logout Redirect 1 (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI AM revoke2 (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI AM revoke1 (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI AM endsession (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > tokenUserinfo (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO > GetUUid (OK=1 KO=0 ) 2024-11-13 06:35:19,855 INFO 2024-11-13 06:35:19,855 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-13 06:35:19,855 INFO [- ] 0% 2024-11-13 06:35:19,855 INFO waiting: 36266 / active: 2 / done: 2 2024-11-13 06:35:19,855 INFO ================================================================================ 2024-11-13 06:35:19,855 INFO 2024-11-13 06:35:19,855 INFO 2024-11-13 06:35:19,855 INFO ================================================================================ 2024-11-13 06:35:19,855 INFO 2024-11-13 05:35:02 GMT 10s elapsed 2024-11-13 06:35:19,855 INFO ---- Requests ------------------------------------------------------------------ 2024-11-13 06:35:19,855 INFO > Global (OK=186 KO=0 ) 2024-11-13 06:35:19,855 INFO > restLoginInitiate (OK=15 KO=0 ) 2024-11-13 06:35:19,855 INFO > restLoginUsernamePasswordCallback (OK=15 KO=0 ) 2024-11-13 06:35:19,855 INFO > Skip 2FA (OK=10 KO=0 ) 2024-11-13 06:35:19,855 INFO > User Authorize PKCE stage (OK=18 KO=0 ) 2024-11-13 06:35:19,855 INFO > User AccessToken PKCE stage (OK=17 KO=0 ) 2024-11-13 06:35:19,855 INFO > RootUserinfo (OK=6 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 1 (OK=3 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=3 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI AM idFromSession (OK=3 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI AM validateGoto (OK=3 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 2 (OK=3 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=3 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI Enduser appAuthHelper1 (OK=3 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI Enduser appAuthHelper2 (OK=3 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 3 (OK=3 KO=0 ) 2024-11-13 06:35:19,855 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=3 KO=0 ) 2024-11-13 06:35:19,855 INFO [...] 2024-11-13 06:35:19,862 INFO > UI AM endsession (OK=7620 KO=0 ) 2024-11-13 06:35:19,862 INFO > tokenUserinfo (OK=7397 KO=0 ) 2024-11-13 06:35:19,862 INFO > GetUUid (OK=11453 KO=0 ) 2024-11-13 06:35:19,862 INFO > GetOpenidConfig (OK=34303 KO=0 ) 2024-11-13 06:35:19,862 INFO > CreatePolicy (OK=11448 KO=4 ) 2024-11-13 06:35:19,862 INFO > getIdToken (OK=2631 KO=0 ) 2024-11-13 06:35:19,862 INFO > getIdToken Redirect 1 (OK=2631 KO=0 ) 2024-11-13 06:35:19,862 INFO > createOrganization (OK=2631 KO=0 ) 2024-11-13 06:35:19,862 INFO > queryOrganization (OK=2630 KO=0 ) 2024-11-13 06:35:19,862 INFO > deleteOrganization (OK=2630 KO=0 ) 2024-11-13 06:35:19,862 INFO > loginTreeWorkflow_restLoginInitiate (OK=1278 KO=0 ) 2024-11-13 06:35:19,862 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1278 KO=0 ) 2024-11-13 06:35:19,862 INFO > ReadPolicy (OK=11435 KO=0 ) 2024-11-13 06:35:19,862 INFO > DeletePolicy (OK=11414 KO=0 ) 2024-11-13 06:35:19,862 INFO > restloginProgressiveProfileCallback (OK=251 KO=0 ) 2024-11-13 06:35:19,862 INFO > loginTreeWorkflow_restloginProgressiveProfileCallback (OK=42 KO=0 ) 2024-11-13 06:35:19,862 INFO ---- Errors -------------------------------------------------------------------- 2024-11-13 06:35:19,862 INFO > tokenUserinfo: No attribute named 'access_token' is defined 254 (95.49%) 2024-11-13 06:35:19,862 INFO > status.find.in([200, 209], 304), found 409 4 ( 1.50%) 2024-11-13 06:35:19,862 INFO > status.find.in([200, 209], 304), found 502 4 ( 1.50%) 2024-11-13 06:35:19,862 INFO > status.find.is(200), but actually found 502 3 ( 1.13%) 2024-11-13 06:35:19,862 INFO > status.find.is(302), but actually found 502 1 ( 0.38%) 2024-11-13 06:35:19,862 INFO 2024-11-13 06:35:19,862 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-13 06:35:19,862 INFO [#########################################################################-] 98% 2024-11-13 06:35:19,862 INFO waiting: 338 / active: 49 / done: 35883 2024-11-13 06:35:19,862 INFO ================================================================================ 2024-11-13 06:35:19,862 INFO 2024-11-13 06:35:19,862 INFO 2024-11-13 06:35:19,862 INFO ================================================================================ 2024-11-13 06:35:19,862 INFO 2024-11-13 06:34:52 GMT 3600s elapsed 2024-11-13 06:35:19,862 INFO ---- Requests ------------------------------------------------------------------ 2024-11-13 06:35:19,862 INFO > Global (OK=501305 KO=12 ) 2024-11-13 06:35:19,862 INFO > restLoginInitiate (OK=34702 KO=1 ) 2024-11-13 06:35:19,862 INFO > restLoginUsernamePasswordCallback (OK=34698 KO=2 ) 2024-11-13 06:35:19,862 INFO > Skip 2FA (OK=19410 KO=0 ) 2024-11-13 06:35:19,862 INFO > User Authorize PKCE stage (OK=42067 KO=1 ) 2024-11-13 06:35:19,862 INFO > User AccessToken PKCE stage (OK=42067 KO=0 ) 2024-11-13 06:35:19,862 INFO > RootUserinfo (OK=26480 KO=0 ) 2024-11-13 06:35:19,862 INFO > UI-Post-Login - stage 1 (OK=7626 KO=0 ) 2024-11-13 06:35:19,862 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=7626 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI AM idFromSession (OK=7625 KO=1 ) 2024-11-13 06:35:19,863 INFO > UI AM validateGoto (OK=7626 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI-Post-Login - stage 2 (OK=7626 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=7626 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI Enduser appAuthHelper1 (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI Enduser appAuthHelper2 (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI-Post-Login - stage 3 (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI Enduser sessioncheck (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI IDM uiconfig (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI IDM version (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI IDM login (OK=7623 KO=2 ) 2024-11-13 06:35:19,863 INFO > UI IDM features (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI IDM privilege (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI IDM managed user schema (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI IDM user (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI IDM dashboard (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI IDM oidcToken (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > userinfo (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI-Logout (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI-Logout Redirect 1 (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI AM revoke2 (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > UI AM revoke1 (OK=7624 KO=1 ) 2024-11-13 06:35:19,863 INFO > UI AM endsession (OK=7625 KO=0 ) 2024-11-13 06:35:19,863 INFO > tokenUserinfo (OK=7407 KO=0 ) 2024-11-13 06:35:19,863 INFO > GetUUid (OK=11470 KO=0 ) 2024-11-13 06:35:19,863 INFO > GetOpenidConfig (OK=34356 KO=0 ) 2024-11-13 06:35:19,863 INFO > CreatePolicy (OK=11466 KO=4 ) 2024-11-13 06:35:19,863 INFO > getIdToken (OK=2639 KO=0 ) 2024-11-13 06:35:19,863 INFO > getIdToken Redirect 1 (OK=2639 KO=0 ) 2024-11-13 06:35:19,863 INFO > createOrganization (OK=2639 KO=0 ) 2024-11-13 06:35:19,863 INFO > queryOrganization (OK=2639 KO=0 ) 2024-11-13 06:35:19,863 INFO > deleteOrganization (OK=2639 KO=0 ) 2024-11-13 06:35:19,863 INFO > loginTreeWorkflow_restLoginInitiate (OK=1279 KO=0 ) 2024-11-13 06:35:19,863 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1279 KO=0 ) 2024-11-13 06:35:19,863 INFO > ReadPolicy (OK=11451 KO=0 ) 2024-11-13 06:35:19,863 INFO > DeletePolicy (OK=11432 KO=0 ) 2024-11-13 06:35:19,863 INFO > restloginProgressiveProfileCallback (OK=252 KO=0 ) 2024-11-13 06:35:19,863 INFO > loginTreeWorkflow_restloginProgressiveProfileCallback (OK=42 KO=0 ) 2024-11-13 06:35:19,863 INFO ---- Errors -------------------------------------------------------------------- 2024-11-13 06:35:19,863 INFO > tokenUserinfo: No attribute named 'access_token' is defined 254 (95.49%) 2024-11-13 06:35:19,863 INFO > status.find.in([200, 209], 304), found 409 4 ( 1.50%) 2024-11-13 06:35:19,863 INFO > status.find.in([200, 209], 304), found 502 4 ( 1.50%) 2024-11-13 06:35:19,863 INFO > status.find.is(200), but actually found 502 3 ( 1.13%) 2024-11-13 06:35:19,863 INFO > status.find.is(302), but actually found 502 1 ( 0.38%) 2024-11-13 06:35:19,863 INFO 2024-11-13 06:35:19,863 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-13 06:35:19,863 INFO [#########################################################################-] 99% 2024-11-13 06:35:19,863 INFO waiting: 288 / active: 51 / done: 35931 2024-11-13 06:35:19,863 INFO ================================================================================ 2024-11-13 06:35:19,863 INFO 2024-11-13 06:35:19,863 INFO [INFO] ------------------------------------------------------------------------ 2024-11-13 06:35:19,863 INFO [INFO] BUILD SUCCESS 2024-11-13 06:35:19,863 INFO [INFO] ------------------------------------------------------------------------ 2024-11-13 06:35:19,863 INFO [INFO] Total time: 01:00 h 2024-11-13 06:35:19,863 INFO [INFO] Finished at: 2024-11-13T06:34:52Z 2024-11-13 06:35:19,863 INFO [INFO] ------------------------------------------------------------------------ 2024-11-13 06:35:19,863 INFO 2024-11-13 06:34:52,225 INFO Gatling simulation run ended successfully 2024-11-13 06:35:19,863 INFO 2024-11-13 06:34:52,225 DEBUG No archive name provided. No archive will be created 2024-11-13 06:35:19,863 INFO ________________________________________________________________________________ [2024-11-13 06:35:19] benchmark-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-13 06:35:19,864 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-13 06:35:19,864 DEBUG ----- output ----- 2024-11-13 06:35:19,864 DEBUG 2024-11-13 06:35:19,864 INFO ________________________________________________________________________________ [2024-11-13 06:35:19] 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-13 06:35:19] benchmark-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-13 06:35:19] benchmark-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped