--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-18 16:17:32] benchmark-10 pre : N/A ________________________________________________________________________________ 2024-11-18 16:17:32 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-18 16:17:32 - 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-18 16:17:32,667 INFO 2024-11-18 16:17:32,667 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 16:17:32,667 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 16:17:32,938 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 16:17:32,938 INFO [loop_until]: OK (rc = 0) 2024-11-18 16:17:32,938 DEBUG --- stdout --- 2024-11-18 16:17:32,939 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 16:17:32,939 DEBUG --- stderr --- 2024-11-18 16:17:32,939 DEBUG 2024-11-18 16:17:32 - INFO: Get overseer pod resources requests memory 2024-11-18 16:17:32,939 INFO 2024-11-18 16:17:32,939 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-18 16:17:32,939 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 16:17:33,232 INFO [loop_until]: OK (rc = 0) 2024-11-18 16:17:33,232 DEBUG --- stdout --- 2024-11-18 16:17:33,232 DEBUG 4Gi 2024-11-18 16:17:33,232 DEBUG --- stderr --- 2024-11-18 16:17:33,232 DEBUG ________________________________________________________________________________ [2024-11-18 16:17:33] benchmark-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-18 16:17:33,232 INFO 2024-11-18 16:17:33,233 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 16:17:33,233 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 16:17:33,504 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 16:17:33,504 INFO [loop_until]: OK (rc = 0) 2024-11-18 16:17:33,504 DEBUG --- stdout --- 2024-11-18 16:17:33,504 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 16:17:33,504 DEBUG --- stderr --- 2024-11-18 16:17:33,504 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-18 16:17:33] benchmark-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-18 16:17:33,504 INFO 2024-11-18 16:17:33,504 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 16:17:33,504 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 16:17:33,776 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 16:17:33,776 INFO [loop_until]: OK (rc = 0) 2024-11-18 16:17:33,776 DEBUG --- stdout --- 2024-11-18 16:17:33,776 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 16:17:33,776 DEBUG --- stderr --- 2024-11-18 16:17:33,776 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-18 16:17:33] 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-18 16:17:33,779 INFO 2024-11-18 16:17:33,779 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-18 16:17:33,779 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 16:17:34,058 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 16:17:34,058 INFO [loop_until]: OK (rc = 0) 2024-11-18 16:17:34,058 DEBUG --- stdout --- 2024-11-18 16:17:34,058 DEBUG WAITING 2024-11-18 16:17:34,058 DEBUG --- stderr --- 2024-11-18 16:17:34,059 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 25 0 --:--:-- --:--:-- --:--:-- 25 ________________________________________________________________________________ [2024-11-18 16:17:34] benchmark-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-18 16:17:34,059 INFO 2024-11-18 16:17:34,059 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 16:17:34,059 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 16:17:34,331 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 16:17:34,331 INFO [loop_until]: OK (rc = 0) 2024-11-18 16:17:34,331 DEBUG --- stdout --- 2024-11-18 16:17:34,331 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 16:17:34,331 DEBUG --- stderr --- 2024-11-18 16:17:34,331 DEBUG 2024-11-18 16:17:34,331 INFO 2024-11-18 16:17:34,331 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-18 16:17:34,331 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 16:17:35,747 INFO [loop_until]: OK (rc = 0) 2024-11-18 16:17:35,747 DEBUG --- stdout --- 2024-11-18 16:17:35,747 DEBUG 2024-11-18 16:17:35,747 DEBUG --- stderr --- 2024-11-18 16:17:35,747 DEBUG Order has been successfully forwarded to overseer 2024-11-18 16:17:35 - INFO: Check if Gatling simulation started successfully 2024-11-18 16:17:35,747 INFO 2024-11-18 16:17:35,747 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 16:17:35,747 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 16:17:36,045 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 16:17:36,045 INFO [loop_until]: OK (rc = 0) 2024-11-18 16:17:36,045 DEBUG --- stdout --- 2024-11-18 16:17:36,045 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 16:17:36,045 DEBUG --- stderr --- 2024-11-18 16:17:36,045 DEBUG 2024-11-18 16:17:36,045 INFO 2024-11-18 16:17:36,045 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-18 16:17:36,045 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 16:17:36,764 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 16:17:42,488 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 16:17:48,198 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-18 16:17:48,199 INFO [loop_until]: OK (rc = 0) 2024-11-18 16:17:48,199 DEBUG --- stdout --- 2024-11-18 16:17:48,199 DEBUG 2024-11-18 16:17:36,364 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp0jlebiyx 2024-11-18 16:17:36,366 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/tmp0jlebiyx/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/tmp0jlebiyx/target/test-classes ... [INFO] compile in 6.9 s [INFO] [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< [INFO] [INFO] [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- [INFO] Running simulation com.forgerock.pyrock.Loadtest. Gatling 3.13.1 is available! (you're using 3.12.0) System property think_time_multiplier not set. No think time emulation will be done in the simulation. Simulation com.forgerock.pyrock.Loadtest started... 2024-11-18 16:17:48,199 DEBUG --- stderr --- 2024-11-18 16:17:48,199 DEBUG ________________________________________________________________________________ [2024-11-18 16:17:48] 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 02s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 03s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 04s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 05s, retry Order is running since 40m 05s, retry Order is running since 42m 06s, retry Order is running since 44m 06s, retry Order is running since 46m 06s, retry Order is running since 48m 07s, retry Order is running since 50m 07s, retry Order is running since 52m 07s, retry Order is running since 54m 07s, retry Order is running since 56m 08s, retry Order is running since 58m 08s, retry PASS : Order stopped by itself after 1h and before timeout (1h 12m) ________________________________________________________________________________ [2024-11-18 17:17:56] benchmark-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-18 17:17:56,975 INFO 2024-11-18 17:17:56,975 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:17:56,975 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:17:57,269 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:17:57,269 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:17:57,269 DEBUG --- stdout --- 2024-11-18 17:17:57,269 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:17:57,269 DEBUG --- stderr --- 2024-11-18 17:17:57,269 DEBUG 2024-11-18 17:17:57,269 INFO 2024-11-18 17:17:57,269 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6644bb6697-rchxr -- rm -f /results/orders/order.json 2024-11-18 17:17:57,269 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 17:17:58,027 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:17:58,027 DEBUG --- stdout --- 2024-11-18 17:17:58,027 DEBUG 2024-11-18 17:17:58,027 DEBUG --- stderr --- 2024-11-18 17:17:58,027 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-18 17:17:58] benchmark-10 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-18 17:17:58 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/order.json 2024-11-18 17:17:58,028 INFO 2024-11-18 17:17:58,028 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:17:58,028 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:17:58,298 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:17:58,299 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:17:58,299 DEBUG --- stdout --- 2024-11-18 17:17:58,299 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:17:58,299 DEBUG --- stderr --- 2024-11-18 17:17:58,299 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 17:17:58,299 INFO 2024-11-18 17:17:58,299 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:17:58,299 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:17:58,570 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:17:58,570 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:17:58,570 DEBUG --- stdout --- 2024-11-18 17:17:58,570 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:17:58,570 DEBUG --- stderr --- 2024-11-18 17:17:58,570 DEBUG 2024-11-18 17:17:58,571 INFO 2024-11-18 17:17:58,571 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-18 17:17:58,571 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 17:17:59,346 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 17:17:59,347 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:17:59,347 DEBUG --- stdout --- 2024-11-18 17:17:59,347 DEBUG /results/orders/benchmark-10/rc.txt file found 2024-11-18 17:17:59,347 DEBUG --- stderr --- 2024-11-18 17:17:59,347 DEBUG 2024-11-18 17:17:59,347 INFO 2024-11-18 17:17:59,347 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:17:59,347 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:17:59,619 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:17:59,619 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:17:59,619 DEBUG --- stdout --- 2024-11-18 17:17:59,619 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:17:59,619 DEBUG --- stderr --- 2024-11-18 17:17:59,619 DEBUG 2024-11-18 17:17:59,619 INFO 2024-11-18 17:17:59,619 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-18 17:18:00,398 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 17:18:00,398 DEBUG --- stdout --- 2024-11-18 17:18:00,399 DEBUG 4 /results/orders/benchmark-10/rc.txt 2024-11-18 17:18:00,399 DEBUG --- stderr --- 2024-11-18 17:18:00,399 DEBUG 2024-11-18 17:18:00,399 INFO - Download file (size 4k) 2024-11-18 17:18:00,399 INFO 2024-11-18 17:18:00,399 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:00,399 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:00,670 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:00,670 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:00,670 DEBUG --- stdout --- 2024-11-18 17:18:00,670 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:00,670 DEBUG --- stderr --- 2024-11-18 17:18:00,670 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-18 17:18:00,670 INFO 2024-11-18 17:18:00,670 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:00,670 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:00,964 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:00,965 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:00,965 DEBUG --- stdout --- 2024-11-18 17:18:00,965 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:00,965 DEBUG --- stderr --- 2024-11-18 17:18:00,965 DEBUG 2024-11-18 17:18:00,965 INFO 2024-11-18 17:18:00,965 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-18 17:18:00,965 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 17:18:01,712 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:01,712 DEBUG --- stdout --- 2024-11-18 17:18:01,712 DEBUG tar: Removing leading `/' from member names 2024-11-18 17:18:01,712 DEBUG --- stderr --- 2024-11-18 17:18:01,712 DEBUG 2024-11-18 17:18:01,712 INFO 2024-11-18 17:18:01,712 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:01,712 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:01,986 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:01,986 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:01,986 DEBUG --- stdout --- 2024-11-18 17:18:01,986 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:01,986 DEBUG --- stderr --- 2024-11-18 17:18:01,986 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 17:18:01,986 INFO 2024-11-18 17:18:01,986 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:01,986 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:02,259 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:02,259 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:02,259 DEBUG --- stdout --- 2024-11-18 17:18:02,259 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:02,259 DEBUG --- stderr --- 2024-11-18 17:18:02,259 DEBUG 2024-11-18 17:18:02,260 INFO 2024-11-18 17:18:02,260 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-18 17:18:02,260 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 17:18:03,046 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 17:18:03,046 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:03,046 DEBUG --- stdout --- 2024-11-18 17:18:03,047 DEBUG /results/orders/benchmark-10/stderr.txt file found 2024-11-18 17:18:03,047 DEBUG --- stderr --- 2024-11-18 17:18:03,047 DEBUG 2024-11-18 17:18:03,047 INFO 2024-11-18 17:18:03,047 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:03,047 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:03,319 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:03,319 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:03,319 DEBUG --- stdout --- 2024-11-18 17:18:03,319 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:03,319 DEBUG --- stderr --- 2024-11-18 17:18:03,319 DEBUG 2024-11-18 17:18:03,319 INFO 2024-11-18 17:18:03,320 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-18 17:18:04,101 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 17:18:04,102 DEBUG --- stdout --- 2024-11-18 17:18:04,102 DEBUG 0 /results/orders/benchmark-10/stderr.txt 2024-11-18 17:18:04,102 DEBUG --- stderr --- 2024-11-18 17:18:04,102 DEBUG 2024-11-18 17:18:04,102 INFO - Download file (size 0k) 2024-11-18 17:18:04,102 INFO 2024-11-18 17:18:04,102 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:04,102 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:04,376 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:04,376 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:04,377 DEBUG --- stdout --- 2024-11-18 17:18:04,377 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:04,377 DEBUG --- stderr --- 2024-11-18 17:18:04,377 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-18 17:18:04,377 INFO 2024-11-18 17:18:04,377 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:04,377 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:04,649 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:04,649 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:04,649 DEBUG --- stdout --- 2024-11-18 17:18:04,649 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:04,649 DEBUG --- stderr --- 2024-11-18 17:18:04,649 DEBUG 2024-11-18 17:18:04,649 INFO 2024-11-18 17:18:04,649 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-18 17:18:04,649 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 17:18:05,390 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:05,390 DEBUG --- stdout --- 2024-11-18 17:18:05,390 DEBUG tar: Removing leading `/' from member names 2024-11-18 17:18:05,390 DEBUG --- stderr --- 2024-11-18 17:18:05,390 DEBUG 2024-11-18 17:18:05,390 INFO 2024-11-18 17:18: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-18 17:18:05,390 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:05,662 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:05,662 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:05,663 DEBUG --- stdout --- 2024-11-18 17:18:05,663 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:05,663 DEBUG --- stderr --- 2024-11-18 17:18:05,664 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 17:18:05,664 INFO 2024-11-18 17:18:05,664 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:05,664 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:05,933 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:05,934 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:05,934 DEBUG --- stdout --- 2024-11-18 17:18:05,934 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:05,934 DEBUG --- stderr --- 2024-11-18 17:18:05,934 DEBUG 2024-11-18 17:18:05,934 INFO 2024-11-18 17:18:05,934 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-18 17:18:05,934 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 17:18:06,712 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 17:18:06,713 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:06,713 DEBUG --- stdout --- 2024-11-18 17:18:06,713 DEBUG /results/orders/benchmark-10/stdout.txt file found 2024-11-18 17:18:06,713 DEBUG --- stderr --- 2024-11-18 17:18:06,713 DEBUG 2024-11-18 17:18:06,713 INFO 2024-11-18 17:18:06,713 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:06,713 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:06,984 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:06,985 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:06,985 DEBUG --- stdout --- 2024-11-18 17:18:06,985 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:06,985 DEBUG --- stderr --- 2024-11-18 17:18:06,985 DEBUG 2024-11-18 17:18:06,985 INFO 2024-11-18 17:18:06,985 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-18 17:18:07,763 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 17:18:07,763 DEBUG --- stdout --- 2024-11-18 17:18:07,763 DEBUG 3328 /results/orders/benchmark-10/stdout.txt 2024-11-18 17:18:07,763 DEBUG --- stderr --- 2024-11-18 17:18:07,763 DEBUG 2024-11-18 17:18:07,763 INFO - Download file (size 3328k) 2024-11-18 17:18:07,763 INFO 2024-11-18 17:18:07,763 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:07,763 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:08,037 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:08,037 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:08,037 DEBUG --- stdout --- 2024-11-18 17:18:08,037 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:08,037 DEBUG --- stderr --- 2024-11-18 17:18:08,037 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-18 17:18:08,037 INFO 2024-11-18 17:18:08,037 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:08,037 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:08,309 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:08,309 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:08,309 DEBUG --- stdout --- 2024-11-18 17:18:08,309 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:08,309 DEBUG --- stderr --- 2024-11-18 17:18:08,309 DEBUG 2024-11-18 17:18:08,309 INFO 2024-11-18 17:18:08,309 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-18 17:18:08,310 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 17:18:09,554 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:09,554 DEBUG --- stdout --- 2024-11-18 17:18:09,554 DEBUG tar: Removing leading `/' from member names 2024-11-18 17:18:09,554 DEBUG --- stderr --- 2024-11-18 17:18:09,554 DEBUG ________________________________________________________________________________ [2024-11-18 17:18:09] benchmark-10 show_cmd : Display order command ________________________________________________________________________________ 2024-11-18 17:18:09,554 INFO 2024-11-18 17:18:09,555 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:09,555 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:09,825 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:09,825 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:09,825 DEBUG --- stdout --- 2024-11-18 17:18:09,825 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:09,825 DEBUG --- stderr --- 2024-11-18 17:18:09,825 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 17:18:09,825 INFO 2024-11-18 17:18:09,825 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:09,825 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:10,101 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:10,101 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:10,101 DEBUG --- stdout --- 2024-11-18 17:18:10,101 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:10,101 DEBUG --- stderr --- 2024-11-18 17:18:10,101 DEBUG 2024-11-18 17:18:10,102 INFO 2024-11-18 17:18:10,102 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-18 17:18:10,102 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 17:18:10,887 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 17:18:10,887 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:10,887 DEBUG --- stdout --- 2024-11-18 17:18:10,887 DEBUG /results/orders/benchmark-10/cmd.txt file found 2024-11-18 17:18:10,887 DEBUG --- stderr --- 2024-11-18 17:18:10,887 DEBUG 2024-11-18 17:18:10,887 INFO 2024-11-18 17:18:10,887 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:10,887 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:11,161 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:11,161 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:11,161 DEBUG --- stdout --- 2024-11-18 17:18:11,161 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:11,161 DEBUG --- stderr --- 2024-11-18 17:18:11,161 DEBUG 2024-11-18 17:18:11,161 INFO 2024-11-18 17:18:11,162 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-18 17:18:11,939 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 17:18:11,939 DEBUG --- stdout --- 2024-11-18 17:18:11,939 DEBUG 4 /results/orders/benchmark-10/cmd.txt 2024-11-18 17:18:11,940 DEBUG --- stderr --- 2024-11-18 17:18:11,940 DEBUG 2024-11-18 17:18:11,940 INFO - Download file (size 4k) 2024-11-18 17:18:11,940 INFO 2024-11-18 17:18:11,940 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:11,940 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:12,212 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:12,212 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:12,212 DEBUG --- stdout --- 2024-11-18 17:18:12,212 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:12,212 DEBUG --- stderr --- 2024-11-18 17:18:12,212 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-18 17:18:12,213 INFO 2024-11-18 17:18:12,213 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 17:18:12,213 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:12,484 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:12,484 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:12,484 DEBUG --- stdout --- 2024-11-18 17:18:12,484 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:12,484 DEBUG --- stderr --- 2024-11-18 17:18:12,484 DEBUG 2024-11-18 17:18:12,485 INFO 2024-11-18 17:18:12,485 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-18 17:18:12,485 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 17:18:13,229 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:13,229 DEBUG --- stdout --- 2024-11-18 17:18:13,229 DEBUG tar: Removing leading `/' from member names 2024-11-18 17:18:13,229 DEBUG --- stderr --- 2024-11-18 17:18:13,229 DEBUG 2024-11-18 17:18:13,230 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-18 17:18:13,230 DEBUG ----- output ----- 2024-11-18 17:18:13,230 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-18 17:18:13,230 INFO ________________________________________________________________________________ [2024-11-18 17:18:13] benchmark-10 show_rc : Display rc order ________________________________________________________________________________ 2024-11-18 17:18:13,230 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-18 17:18:13,230 DEBUG ----- output ----- 2024-11-18 17:18:13,230 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-18 17:18:13,230 INFO ________________________________________________________________________________ [2024-11-18 17:18:13] benchmark-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-18 17:18:13,235 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 44375 lines): 2024-11-18 17:18:13,235 DEBUG ----- output ----- 2024-11-18 17:18:13,235 INFO 2024-11-18 16:17:36,364 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp0jlebiyx 2024-11-18 17:18:13,235 INFO 2024-11-18 16:17:36,366 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-18 17:18:13,235 INFO [INFO] Scanning for projects... 2024-11-18 17:18:13,235 INFO [INFO] 2024-11-18 17:18:13,235 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-18 17:18:13,235 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-18 17:18:13,235 INFO [INFO] from pom.xml 2024-11-18 17:18:13,235 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-18 17:18:13,235 INFO [INFO] 2024-11-18 17:18:13,235 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-18 17:18:13,235 INFO [INFO] 2024-11-18 17:18:13,235 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-18 17:18:13,235 INFO [INFO] skip non existing resourceDirectory /tmp/tmp0jlebiyx/src/main/resources 2024-11-18 17:18:13,235 INFO [INFO] 2024-11-18 17:18:13,235 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-18 17:18:13,235 INFO [INFO] No sources to compile 2024-11-18 17:18:13,235 INFO [INFO] 2024-11-18 17:18:13,235 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-18 17:18:13,235 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-18 17:18:13,235 INFO [INFO] 2024-11-18 17:18:13,235 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-18 17:18:13,235 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-18 17:18:13,235 INFO [INFO] 2024-11-18 17:18:13,235 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-18 17:18:13,235 INFO [INFO] compiling 38 Scala sources to /tmp/tmp0jlebiyx/target/test-classes ... 2024-11-18 17:18:13,235 INFO [INFO] compile in 6.9 s 2024-11-18 17:18:13,235 INFO [INFO] 2024-11-18 17:18:13,235 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-18 17:18:13,235 INFO [INFO] 2024-11-18 17:18:13,235 INFO [INFO] 2024-11-18 17:18:13,235 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-18 17:18:13,235 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-18 17:18:13,235 INFO Gatling 3.13.1 is available! (you're using 3.12.0) 2024-11-18 17:18:13,235 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-18 17:18:13,235 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-18 17:18:13,235 INFO 2024-11-18 17:18:13,235 INFO ================================================================================ 2024-11-18 17:18:13,235 INFO 2024-11-18 16:17:52 GMT 5s elapsed 2024-11-18 17:18:13,235 INFO ---- Requests ------------------------------------------------------------------ 2024-11-18 17:18:13,235 INFO > Global (OK=21 KO=0 ) 2024-11-18 17:18:13,235 INFO > restLoginInitiate (OK=4 KO=0 ) 2024-11-18 17:18:13,236 INFO > restLoginUsernamePasswordCallback (OK=4 KO=0 ) 2024-11-18 17:18:13,236 INFO > User Authorize PKCE stage (OK=4 KO=0 ) 2024-11-18 17:18:13,236 INFO > User AccessToken PKCE stage (OK=4 KO=0 ) 2024-11-18 17:18:13,236 INFO > tokenUserinfo (OK=2 KO=0 ) 2024-11-18 17:18:13,236 INFO > Skip 2FA (OK=1 KO=0 ) 2024-11-18 17:18:13,236 INFO > RootUserinfo (OK=2 KO=0 ) 2024-11-18 17:18:13,236 INFO 2024-11-18 17:18:13,236 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-18 17:18:13,236 INFO [- ] 0% 2024-11-18 17:18:13,236 INFO waiting: 36281 / active: 2 / done: 2 2024-11-18 17:18:13,236 INFO ================================================================================ 2024-11-18 17:18:13,236 INFO 2024-11-18 17:18:13,236 INFO 2024-11-18 17:18:13,236 INFO ================================================================================ 2024-11-18 17:18:13,236 INFO 2024-11-18 16:17:57 GMT 10s elapsed 2024-11-18 17:18:13,236 INFO ---- Requests ------------------------------------------------------------------ 2024-11-18 17:18:13,236 INFO > Global (OK=179 KO=0 ) 2024-11-18 17:18:13,236 INFO > restLoginInitiate (OK=16 KO=0 ) 2024-11-18 17:18:13,236 INFO > restLoginUsernamePasswordCallback (OK=16 KO=0 ) 2024-11-18 17:18:13,236 INFO > User Authorize PKCE stage (OK=19 KO=0 ) 2024-11-18 17:18:13,236 INFO > User AccessToken PKCE stage (OK=19 KO=0 ) 2024-11-18 17:18:13,236 INFO > tokenUserinfo (OK=7 KO=0 ) 2024-11-18 17:18:13,236 INFO > Skip 2FA (OK=5 KO=0 ) 2024-11-18 17:18:13,236 INFO > RootUserinfo (OK=8 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI-Post-Login - stage 1 (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI AM idFromSession (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI AM validateGoto (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI-Post-Login - stage 2 (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI Enduser appAuthHelper1 (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI Enduser appAuthHelper2 (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI-Post-Login - stage 3 (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI IDM uiconfig (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI Enduser sessioncheck (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI IDM version (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI IDM privilege (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI IDM login (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI IDM features (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI IDM dashboard (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI IDM managed user schema (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI IDM user (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI IDM oidcToken (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > userinfo (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI-Logout (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI-Logout Redirect 1 (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI AM revoke2 (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI AM revoke1 (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > UI AM endsession (OK=3 KO=0 ) 2024-11-18 17:18:13,236 INFO > getIdToken (OK=1 KO=0 ) 2024-11-18 17:18:13,236 INFO > getIdToken Redirect 1 (OK=1 KO=0 ) 2024-11-18 17:18:13,236 INFO > createOrganization (OK=1 KO=0 ) 2024-11-18 17:18:13,236 INFO > queryOrganization (OK=1 KO=0 ) 2024-11-18 17:18:13,236 INFO > deleteOrganization (OK=1 KO=0 ) 2024-11-18 17:18:13,236 INFO > GetUUid (OK=2 KO=0 ) 2024-11-18 17:18:13,236 INFO > GetOpenidConfig (OK=2 KO=0 ) 2024-11-18 17:18:13,236 INFO > CreatePolicy (OK=2 KO=0 ) 2024-11-18 17:18:13,236 INFO 2024-11-18 17:18:13,236 INFO [...] 2024-11-18 17:18:13,243 INFO > UI AM revoke1 (OK=7689 KO=0 ) 2024-11-18 17:18:13,243 INFO > UI AM endsession (OK=7689 KO=0 ) 2024-11-18 17:18:13,243 INFO > getIdToken (OK=2618 KO=0 ) 2024-11-18 17:18:13,243 INFO > getIdToken Redirect 1 (OK=2618 KO=0 ) 2024-11-18 17:18:13,243 INFO > createOrganization (OK=2618 KO=0 ) 2024-11-18 17:18:13,243 INFO > queryOrganization (OK=2618 KO=0 ) 2024-11-18 17:18:13,243 INFO > deleteOrganization (OK=2618 KO=0 ) 2024-11-18 17:18:13,243 INFO > GetUUid (OK=11616 KO=0 ) 2024-11-18 17:18:13,243 INFO > GetOpenidConfig (OK=34782 KO=0 ) 2024-11-18 17:18:13,243 INFO > CreatePolicy (OK=11615 KO=1 ) 2024-11-18 17:18:13,243 INFO > ReadPolicy (OK=11590 KO=1 ) 2024-11-18 17:18:13,243 INFO > loginTreeWorkflow_restLoginInitiate (OK=1300 KO=0 ) 2024-11-18 17:18:13,243 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1300 KO=0 ) 2024-11-18 17:18:13,243 INFO > DeletePolicy (OK=11574 KO=1 ) 2024-11-18 17:18:13,243 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-11-18 17:18:13,243 INFO > loginTreeWorkflow_restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-11-18 17:18:13,243 INFO ---- Errors -------------------------------------------------------------------- 2024-11-18 17:18:13,243 INFO > tokenUserinfo: No attribute named 'access_token' is defined 9 (60.00%) 2024-11-18 17:18:13,243 INFO > status.find.in([200, 209], 304), found 502 2 (13.33%) 2024-11-18 17:18:13,243 INFO > status.find.in([200, 209], 304), found 404 2 (13.33%) 2024-11-18 17:18:13,243 INFO > status.find.is(302), but actually found 502 1 ( 6.67%) 2024-11-18 17:18:13,243 INFO > status.find.is(200), but actually found 502 1 ( 6.67%) 2024-11-18 17:18:13,243 INFO 2024-11-18 17:18:13,243 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-18 17:18:13,243 INFO [#########################################################################-] 98% 2024-11-18 17:18:13,243 INFO waiting: 324 / active: 50 / done: 35911 2024-11-18 17:18:13,243 INFO ================================================================================ 2024-11-18 17:18:13,243 INFO 2024-11-18 17:18:13,243 INFO 2024-11-18 17:18:13,243 INFO ================================================================================ 2024-11-18 17:18:13,243 INFO 2024-11-18 17:17:47 GMT 3600s elapsed 2024-11-18 17:18:13,243 INFO ---- Requests ------------------------------------------------------------------ 2024-11-18 17:18:13,243 INFO > Global (OK=503292 KO=6 ) 2024-11-18 17:18:13,243 INFO > restLoginInitiate (OK=34659 KO=1 ) 2024-11-18 17:18:13,243 INFO > restLoginUsernamePasswordCallback (OK=34658 KO=0 ) 2024-11-18 17:18:13,243 INFO > User Authorize PKCE stage (OK=42337 KO=1 ) 2024-11-18 17:18:13,243 INFO > User AccessToken PKCE stage (OK=42336 KO=1 ) 2024-11-18 17:18:13,243 INFO > tokenUserinfo (OK=7611 KO=0 ) 2024-11-18 17:18:13,243 INFO > Skip 2FA (OK=19348 KO=0 ) 2024-11-18 17:18:13,243 INFO > RootUserinfo (OK=25553 KO=0 ) 2024-11-18 17:18:13,243 INFO > UI-Post-Login - stage 1 (OK=7689 KO=0 ) 2024-11-18 17:18:13,243 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=7689 KO=0 ) 2024-11-18 17:18:13,243 INFO > UI AM idFromSession (OK=7689 KO=0 ) 2024-11-18 17:18:13,243 INFO > UI AM validateGoto (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI-Post-Login - stage 2 (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI Enduser appAuthHelper1 (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI Enduser appAuthHelper2 (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI-Post-Login - stage 3 (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI IDM uiconfig (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI Enduser sessioncheck (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI IDM version (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI IDM privilege (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI IDM login (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI IDM features (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI IDM dashboard (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI IDM managed user schema (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI IDM user (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI IDM oidcToken (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > userinfo (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI-Logout (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI-Logout Redirect 1 (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI AM revoke2 (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI AM revoke1 (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > UI AM endsession (OK=7689 KO=0 ) 2024-11-18 17:18:13,244 INFO > getIdToken (OK=2618 KO=0 ) 2024-11-18 17:18:13,244 INFO > getIdToken Redirect 1 (OK=2618 KO=0 ) 2024-11-18 17:18:13,244 INFO > createOrganization (OK=2618 KO=0 ) 2024-11-18 17:18:13,244 INFO > queryOrganization (OK=2618 KO=0 ) 2024-11-18 17:18:13,244 INFO > deleteOrganization (OK=2618 KO=0 ) 2024-11-18 17:18:13,244 INFO > GetUUid (OK=11616 KO=0 ) 2024-11-18 17:18:13,244 INFO > GetOpenidConfig (OK=34782 KO=0 ) 2024-11-18 17:18:13,244 INFO > CreatePolicy (OK=11615 KO=1 ) 2024-11-18 17:18:13,244 INFO > ReadPolicy (OK=11590 KO=1 ) 2024-11-18 17:18:13,244 INFO > loginTreeWorkflow_restLoginInitiate (OK=1300 KO=0 ) 2024-11-18 17:18:13,244 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1300 KO=0 ) 2024-11-18 17:18:13,244 INFO > DeletePolicy (OK=11574 KO=1 ) 2024-11-18 17:18:13,244 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-11-18 17:18:13,244 INFO > loginTreeWorkflow_restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-11-18 17:18:13,244 INFO ---- Errors -------------------------------------------------------------------- 2024-11-18 17:18:13,244 INFO > tokenUserinfo: No attribute named 'access_token' is defined 9 (60.00%) 2024-11-18 17:18:13,244 INFO > status.find.in([200, 209], 304), found 502 2 (13.33%) 2024-11-18 17:18:13,244 INFO > status.find.in([200, 209], 304), found 404 2 (13.33%) 2024-11-18 17:18:13,244 INFO > status.find.is(302), but actually found 502 1 ( 6.67%) 2024-11-18 17:18:13,244 INFO > status.find.is(200), but actually found 502 1 ( 6.67%) 2024-11-18 17:18:13,244 INFO 2024-11-18 17:18:13,244 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-18 17:18:13,244 INFO [#########################################################################-] 98% 2024-11-18 17:18:13,244 INFO waiting: 324 / active: 50 / done: 35911 2024-11-18 17:18:13,244 INFO ================================================================================ 2024-11-18 17:18:13,244 INFO 2024-11-18 17:18:13,244 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 17:18:13,244 INFO [INFO] BUILD SUCCESS 2024-11-18 17:18:13,244 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 17:18:13,244 INFO [INFO] Total time: 01:00 h 2024-11-18 17:18:13,244 INFO [INFO] Finished at: 2024-11-18T17:17:47Z 2024-11-18 17:18:13,244 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 17:18:13,244 INFO 2024-11-18 17:17:47,258 INFO Gatling simulation run ended successfully 2024-11-18 17:18:13,244 INFO 2024-11-18 17:17:47,259 DEBUG No archive name provided. No archive will be created 2024-11-18 17:18:13,244 INFO ________________________________________________________________________________ [2024-11-18 17:18:13] benchmark-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-18 17:18:13,245 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-18 17:18:13,245 DEBUG ----- output ----- 2024-11-18 17:18:13,245 DEBUG 2024-11-18 17:18:13,245 INFO ________________________________________________________________________________ [2024-11-18 17:18:13] 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-18 17:18:13] benchmark-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-18 17:18:13] benchmark-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped