--Task-- name: benchmark-25 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates below loop: False interval: None dependencies: [] wait_for: ['benchmark-15'] preceding_task: benchmark-15 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 25} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-21 20:12:42] benchmark-25 pre : N/A ________________________________________________________________________________ 2024-11-21 20:12:42 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-21 20:12:42 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-25/stdout.txt Pod log : /results/orders/benchmark-25 2024-11-21 20:12:42,657 INFO 2024-11-21 20:12:42,657 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-21 20:12:42,657 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 20:12:42,929 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 20:12:42,929 INFO [loop_until]: OK (rc = 0) 2024-11-21 20:12:42,929 DEBUG --- stdout --- 2024-11-21 20:12:42,929 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 20:12:42,929 DEBUG --- stderr --- 2024-11-21 20:12:42,929 DEBUG ________________________________________________________________________________ [2024-11-21 20:12:42] benchmark-25 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-21 20:12:42,929 INFO 2024-11-21 20:12:42,929 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-21 20:12:42,929 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 20:12:43,201 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 20:12:43,201 INFO [loop_until]: OK (rc = 0) 2024-11-21 20:12:43,201 DEBUG --- stdout --- 2024-11-21 20:12:43,201 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 20:12:43,201 DEBUG --- stderr --- 2024-11-21 20:12:43,201 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-25" --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 -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=25 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-21 20:12:43] benchmark-25 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-21 20:12:43,202 INFO 2024-11-21 20:12:43,202 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-21 20:12:43,202 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 20:12:43,519 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 20:12:43,519 INFO [loop_until]: OK (rc = 0) 2024-11-21 20:12:43,519 DEBUG --- stdout --- 2024-11-21 20:12:43,519 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 20:12:43,519 DEBUG --- stderr --- 2024-11-21 20:12:43,519 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/order.json { "name": "benchmark-25", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-25\" --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 -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=25 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-25" } ________________________________________________________________________________ [2024-11-21 20:12:43] benchmark-25 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-21 20:12:43,523 INFO 2024-11-21 20:12:43,523 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-21 20:12:43,523 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-21 20:12:43,737 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-21 20:12:43,737 INFO [loop_until]: OK (rc = 0) 2024-11-21 20:12:43,737 DEBUG --- stdout --- 2024-11-21 20:12:43,737 DEBUG WAITING 2024-11-21 20:12:43,737 DEBUG --- stderr --- 2024-11-21 20:12:43,737 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 34 0 --:--:-- --:--:-- --:--:-- 34 ________________________________________________________________________________ [2024-11-21 20:12:43] benchmark-25 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-21 20:12:43,738 INFO 2024-11-21 20:12:43,738 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-21 20:12:43,738 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 20:12:44,009 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 20:12:44,010 INFO [loop_until]: OK (rc = 0) 2024-11-21 20:12:44,010 DEBUG --- stdout --- 2024-11-21 20:12:44,010 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 20:12:44,010 DEBUG --- stderr --- 2024-11-21 20:12:44,010 DEBUG 2024-11-21 20:12:44,010 INFO 2024-11-21 20:12:44,010 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/03_benchmark-25/order.json overseer-0-6644bb6697-rchxr:/results/orders/order.json 2024-11-21 20:12:44,010 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 20:12:45,335 INFO [loop_until]: OK (rc = 0) 2024-11-21 20:12:45,335 DEBUG --- stdout --- 2024-11-21 20:12:45,335 DEBUG 2024-11-21 20:12:45,335 DEBUG --- stderr --- 2024-11-21 20:12:45,335 DEBUG Order has been successfully forwarded to overseer 2024-11-21 20:12:45 - INFO: Check if Gatling simulation started successfully 2024-11-21 20:12:45,335 INFO 2024-11-21 20:12:45,335 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-21 20:12:45,335 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 20:12:45,608 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 20:12:45,608 INFO [loop_until]: OK (rc = 0) 2024-11-21 20:12:45,608 DEBUG --- stdout --- 2024-11-21 20:12:45,608 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 20:12:45,608 DEBUG --- stderr --- 2024-11-21 20:12:45,608 DEBUG 2024-11-21 20:12:45,609 INFO 2024-11-21 20:12:45,609 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-25/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-21 20:12:45,609 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-21 20:12:46,320 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-21 20:12:52,033 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-21 20:12:57,746 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-21 20:12:57,746 INFO [loop_until]: OK (rc = 0) 2024-11-21 20:12:57,746 DEBUG --- stdout --- 2024-11-21 20:12:57,746 DEBUG 2024-11-21 20:12:46,142 DEBUG Creating results folder /results/gatling/benchmark-25 2024-11-21 20:12:46,150 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpeify0gv6 2024-11-21 20:12:46,152 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-25" -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 -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=25 -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/tmpeify0gv6/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/tmpeify0gv6/target/test-classes ... [INFO] compile in 6.8 s [INFO] [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< [INFO] [INFO] [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- [INFO] Running simulation com.forgerock.pyrock.Loadtest. Gatling 3.13.1 is available! (you're using 3.12.0) System property think_time_multiplier not set. No think time emulation will be done in the simulation. Simulation com.forgerock.pyrock.Loadtest started... 2024-11-21 20:12:57,746 DEBUG --- stderr --- 2024-11-21 20:12:57,746 DEBUG ________________________________________________________________________________ [2024-11-21 20:12:57] benchmark-25 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m. Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 02s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 03s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 04s, retry Order is running since 34m 04s, retry Order is running since 36m 05s, retry Order is running since 38m 05s, retry Order is running since 40m 05s, retry Order is running since 42m 06s, retry Order is running since 44m 06s, retry Order is running since 46m 06s, retry Order is running since 48m 06s, 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-21 21:13:06] benchmark-25 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-21 21:13:06,473 INFO 2024-11-21 21:13:06,473 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-21 21:13:06,473 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:06,770 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:06,770 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:06,771 DEBUG --- stdout --- 2024-11-21 21:13:06,771 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:06,771 DEBUG --- stderr --- 2024-11-21 21:13:06,771 DEBUG 2024-11-21 21:13:06,771 INFO 2024-11-21 21:13:06,771 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-21 21:13:06,771 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 21:13:07,530 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:07,530 DEBUG --- stdout --- 2024-11-21 21:13:07,530 DEBUG 2024-11-21 21:13:07,530 DEBUG --- stderr --- 2024-11-21 21:13:07,530 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-21 21:13:07] benchmark-25 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-21 21:13:07 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/order.json 2024-11-21 21:13:07,531 INFO 2024-11-21 21:13:07,531 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-21 21:13:07,531 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:07,802 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:07,802 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:07,802 DEBUG --- stdout --- 2024-11-21 21:13:07,802 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:07,802 DEBUG --- stderr --- 2024-11-21 21:13:07,802 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-21 21:13:07,803 INFO 2024-11-21 21:13:07,803 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-21 21:13:07,803 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:08,076 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:08,076 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:08,076 DEBUG --- stdout --- 2024-11-21 21:13:08,076 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:08,076 DEBUG --- stderr --- 2024-11-21 21:13:08,076 DEBUG 2024-11-21 21:13:08,077 INFO 2024-11-21 21:13:08,077 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-25/rc.txt" ]; then echo "/results/orders/benchmark-25/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-25/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-25/rc.txt file found" 2024-11-21 21:13:08,077 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 21:13:08,937 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-21 21:13:08,938 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:08,938 DEBUG --- stdout --- 2024-11-21 21:13:08,938 DEBUG /results/orders/benchmark-25/rc.txt file found 2024-11-21 21:13:08,939 DEBUG --- stderr --- 2024-11-21 21:13:08,939 DEBUG 2024-11-21 21:13:08,939 INFO 2024-11-21 21:13:08,939 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-21 21:13:08,939 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:09,212 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:09,212 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:09,212 DEBUG --- stdout --- 2024-11-21 21:13:09,212 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:09,212 DEBUG --- stderr --- 2024-11-21 21:13:09,212 DEBUG 2024-11-21 21:13:09,212 INFO 2024-11-21 21:13:09,212 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-25/rc.txt 2024-11-21 21:13:10,000 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-21 21:13:10,000 DEBUG --- stdout --- 2024-11-21 21:13:10,000 DEBUG 4 /results/orders/benchmark-25/rc.txt 2024-11-21 21:13:10,000 DEBUG --- stderr --- 2024-11-21 21:13:10,000 DEBUG 2024-11-21 21:13:10,000 INFO - Download file (size 4k) 2024-11-21 21:13:10,000 INFO 2024-11-21 21:13:10,000 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-21 21:13:10,000 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:10,272 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:10,272 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:10,272 DEBUG --- stdout --- 2024-11-21 21:13:10,272 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:10,272 DEBUG --- stderr --- 2024-11-21 21:13:10,272 DEBUG source : /results/orders/benchmark-25/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/rc.txt 2024-11-21 21:13:10,273 INFO 2024-11-21 21:13:10,273 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-21 21:13:10,273 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:10,545 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:10,546 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:10,546 DEBUG --- stdout --- 2024-11-21 21:13:10,546 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:10,546 DEBUG --- stderr --- 2024-11-21 21:13:10,546 DEBUG 2024-11-21 21:13:10,546 INFO 2024-11-21 21:13:10,546 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-25/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/rc.txt 2024-11-21 21:13:10,546 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-21 21:13:11,290 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:11,290 DEBUG --- stdout --- 2024-11-21 21:13:11,290 DEBUG tar: Removing leading `/' from member names 2024-11-21 21:13:11,290 DEBUG --- stderr --- 2024-11-21 21:13:11,290 DEBUG 2024-11-21 21:13:11,291 INFO 2024-11-21 21:13:11,291 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-21 21:13:11,291 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:11,566 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:11,566 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:11,566 DEBUG --- stdout --- 2024-11-21 21:13:11,566 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:11,566 DEBUG --- stderr --- 2024-11-21 21:13:11,566 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-21 21:13:11,567 INFO 2024-11-21 21:13:11,567 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-21 21:13:11,567 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:11,839 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:11,839 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:11,839 DEBUG --- stdout --- 2024-11-21 21:13:11,839 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:11,839 DEBUG --- stderr --- 2024-11-21 21:13:11,839 DEBUG 2024-11-21 21:13:11,840 INFO 2024-11-21 21:13:11,840 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-25/stderr.txt" ]; then echo "/results/orders/benchmark-25/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-25/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-25/stderr.txt file found" 2024-11-21 21:13:11,840 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 21:13:12,618 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-21 21:13:12,618 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:12,618 DEBUG --- stdout --- 2024-11-21 21:13:12,618 DEBUG /results/orders/benchmark-25/stderr.txt file found 2024-11-21 21:13:12,618 DEBUG --- stderr --- 2024-11-21 21:13:12,618 DEBUG 2024-11-21 21:13:12,618 INFO 2024-11-21 21:13:12,618 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-21 21:13:12,618 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:12,889 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:12,889 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:12,889 DEBUG --- stdout --- 2024-11-21 21:13:12,889 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:12,889 DEBUG --- stderr --- 2024-11-21 21:13:12,889 DEBUG 2024-11-21 21:13:12,889 INFO 2024-11-21 21:13:12,889 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-25/stderr.txt 2024-11-21 21:13:13,666 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-21 21:13:13,666 DEBUG --- stdout --- 2024-11-21 21:13:13,666 DEBUG 0 /results/orders/benchmark-25/stderr.txt 2024-11-21 21:13:13,666 DEBUG --- stderr --- 2024-11-21 21:13:13,666 DEBUG 2024-11-21 21:13:13,666 INFO - Download file (size 0k) 2024-11-21 21:13:13,667 INFO 2024-11-21 21:13:13,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-21 21:13:13,667 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:13,940 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:13,940 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:13,940 DEBUG --- stdout --- 2024-11-21 21:13:13,940 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:13,940 DEBUG --- stderr --- 2024-11-21 21:13:13,940 DEBUG source : /results/orders/benchmark-25/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/stderr.txt 2024-11-21 21:13:13,941 INFO 2024-11-21 21:13:13,941 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-21 21:13:13,941 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:14,212 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:14,212 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:14,212 DEBUG --- stdout --- 2024-11-21 21:13:14,212 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:14,212 DEBUG --- stderr --- 2024-11-21 21:13:14,212 DEBUG 2024-11-21 21:13:14,213 INFO 2024-11-21 21:13:14,213 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-25/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/stderr.txt 2024-11-21 21:13:14,213 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-21 21:13:14,956 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:14,956 DEBUG --- stdout --- 2024-11-21 21:13:14,956 DEBUG tar: Removing leading `/' from member names 2024-11-21 21:13:14,956 DEBUG --- stderr --- 2024-11-21 21:13:14,956 DEBUG 2024-11-21 21:13:14,957 INFO 2024-11-21 21:13:14,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-21 21:13:14,957 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:15,231 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:15,231 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:15,232 DEBUG --- stdout --- 2024-11-21 21:13:15,232 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:15,232 DEBUG --- stderr --- 2024-11-21 21:13:15,232 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-21 21:13:15,232 INFO 2024-11-21 21:13:15,232 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-21 21:13:15,232 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:15,503 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:15,503 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:15,503 DEBUG --- stdout --- 2024-11-21 21:13:15,503 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:15,503 DEBUG --- stderr --- 2024-11-21 21:13:15,503 DEBUG 2024-11-21 21:13:15,504 INFO 2024-11-21 21:13:15,504 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-25/stdout.txt" ]; then echo "/results/orders/benchmark-25/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-25/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-25/stdout.txt file found" 2024-11-21 21:13:15,504 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 21:13:16,283 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-21 21:13:16,283 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:16,283 DEBUG --- stdout --- 2024-11-21 21:13:16,283 DEBUG /results/orders/benchmark-25/stdout.txt file found 2024-11-21 21:13:16,283 DEBUG --- stderr --- 2024-11-21 21:13:16,283 DEBUG 2024-11-21 21:13:16,284 INFO 2024-11-21 21:13:16,284 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-21 21:13:16,284 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:16,560 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:16,560 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:16,560 DEBUG --- stdout --- 2024-11-21 21:13:16,560 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:16,560 DEBUG --- stderr --- 2024-11-21 21:13:16,560 DEBUG 2024-11-21 21:13:16,560 INFO 2024-11-21 21:13:16,560 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-25/stdout.txt 2024-11-21 21:13:17,342 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-21 21:13:17,342 DEBUG --- stdout --- 2024-11-21 21:13:17,342 DEBUG 3756 /results/orders/benchmark-25/stdout.txt 2024-11-21 21:13:17,342 DEBUG --- stderr --- 2024-11-21 21:13:17,342 DEBUG 2024-11-21 21:13:17,342 INFO - Download file (size 3756k) 2024-11-21 21:13:17,343 INFO 2024-11-21 21:13:17,343 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-21 21:13:17,343 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:17,614 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:17,614 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:17,614 DEBUG --- stdout --- 2024-11-21 21:13:17,614 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:17,614 DEBUG --- stderr --- 2024-11-21 21:13:17,614 DEBUG source : /results/orders/benchmark-25/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/stdout.txt 2024-11-21 21:13:17,614 INFO 2024-11-21 21:13:17,614 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-21 21:13:17,614 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:17,886 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:17,886 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:17,886 DEBUG --- stdout --- 2024-11-21 21:13:17,886 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:17,886 DEBUG --- stderr --- 2024-11-21 21:13:17,886 DEBUG 2024-11-21 21:13:17,887 INFO 2024-11-21 21:13:17,887 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-25/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/stdout.txt 2024-11-21 21:13:17,887 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-21 21:13:19,115 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:19,115 DEBUG --- stdout --- 2024-11-21 21:13:19,115 DEBUG tar: Removing leading `/' from member names 2024-11-21 21:13:19,115 DEBUG --- stderr --- 2024-11-21 21:13:19,115 DEBUG ________________________________________________________________________________ [2024-11-21 21:13:19] benchmark-25 show_cmd : Display order command ________________________________________________________________________________ 2024-11-21 21:13:19,115 INFO 2024-11-21 21:13:19,115 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-21 21:13:19,115 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:19,388 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:19,389 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:19,389 DEBUG --- stdout --- 2024-11-21 21:13:19,389 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:19,389 DEBUG --- stderr --- 2024-11-21 21:13:19,389 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-21 21:13:19,389 INFO 2024-11-21 21:13:19,389 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-21 21:13:19,389 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:19,675 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:19,675 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:19,675 DEBUG --- stdout --- 2024-11-21 21:13:19,675 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:19,675 DEBUG --- stderr --- 2024-11-21 21:13:19,675 DEBUG 2024-11-21 21:13:19,676 INFO 2024-11-21 21:13:19,676 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-25/cmd.txt" ]; then echo "/results/orders/benchmark-25/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-25/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-25/cmd.txt file found" 2024-11-21 21:13:19,676 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-21 21:13:20,454 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-21 21:13:20,454 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:20,454 DEBUG --- stdout --- 2024-11-21 21:13:20,454 DEBUG /results/orders/benchmark-25/cmd.txt file found 2024-11-21 21:13:20,454 DEBUG --- stderr --- 2024-11-21 21:13:20,454 DEBUG 2024-11-21 21:13:20,455 INFO 2024-11-21 21:13:20,455 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-21 21:13:20,455 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:20,727 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:20,727 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:20,727 DEBUG --- stdout --- 2024-11-21 21:13:20,727 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:20,727 DEBUG --- stderr --- 2024-11-21 21:13:20,727 DEBUG 2024-11-21 21:13:20,727 INFO 2024-11-21 21:13:20,728 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-25/cmd.txt 2024-11-21 21:13:21,502 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-21 21:13:21,502 DEBUG --- stdout --- 2024-11-21 21:13:21,502 DEBUG 4 /results/orders/benchmark-25/cmd.txt 2024-11-21 21:13:21,502 DEBUG --- stderr --- 2024-11-21 21:13:21,502 DEBUG 2024-11-21 21:13:21,502 INFO - Download file (size 4k) 2024-11-21 21:13:21,503 INFO 2024-11-21 21:13:21,503 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-21 21:13:21,503 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:21,777 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:21,777 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:21,777 DEBUG --- stdout --- 2024-11-21 21:13:21,777 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:21,777 DEBUG --- stderr --- 2024-11-21 21:13:21,777 DEBUG source : /results/orders/benchmark-25/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/cmd.txt 2024-11-21 21:13:21,778 INFO 2024-11-21 21:13:21,778 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-21 21:13:21,778 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-21 21:13:22,050 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-21 21:13:22,050 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:22,050 DEBUG --- stdout --- 2024-11-21 21:13:22,050 DEBUG overseer-0-6644bb6697-rchxr 2024-11-21 21:13:22,050 DEBUG --- stderr --- 2024-11-21 21:13:22,050 DEBUG 2024-11-21 21:13:22,050 INFO 2024-11-21 21:13:22,050 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-25/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/cmd.txt 2024-11-21 21:13:22,050 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-21 21:13:22,796 INFO [loop_until]: OK (rc = 0) 2024-11-21 21:13:22,796 DEBUG --- stdout --- 2024-11-21 21:13:22,796 DEBUG tar: Removing leading `/' from member names 2024-11-21 21:13:22,796 DEBUG --- stderr --- 2024-11-21 21:13:22,796 DEBUG 2024-11-21 21:13:22,796 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/cmd.txt is small (1 lines), printing all lines: 2024-11-21 21:13:22,796 DEBUG ----- output ----- 2024-11-21 21:13:22,796 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-25" --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 -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=25 -Duser_password=Pa_ssw0rd" 2024-11-21 21:13:22,796 INFO ________________________________________________________________________________ [2024-11-21 21:13:22] benchmark-25 show_rc : Display rc order ________________________________________________________________________________ 2024-11-21 21:13:22,796 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/rc.txt is small (1 lines), printing all lines: 2024-11-21 21:13:22,796 DEBUG ----- output ----- 2024-11-21 21:13:22,796 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-21 21:13:22,796 INFO ________________________________________________________________________________ [2024-11-21 21:13:22] benchmark-25 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-21 21:13:22,801 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/03_benchmark-25/stdout.txt is 49026 lines): 2024-11-21 21:13:22,801 DEBUG ----- output ----- 2024-11-21 21:13:22,801 INFO 2024-11-21 20:12:46,142 DEBUG Creating results folder /results/gatling/benchmark-25 2024-11-21 21:13:22,801 INFO 2024-11-21 20:12:46,150 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpeify0gv6 2024-11-21 21:13:22,801 INFO 2024-11-21 20:12:46,152 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-25" -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 -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=25 -Duser_password=**** 2024-11-21 21:13:22,801 INFO [INFO] Scanning for projects... 2024-11-21 21:13:22,801 INFO [INFO] 2024-11-21 21:13:22,801 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-21 21:13:22,801 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-21 21:13:22,802 INFO [INFO] from pom.xml 2024-11-21 21:13:22,802 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-21 21:13:22,802 INFO [INFO] 2024-11-21 21:13:22,802 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-21 21:13:22,802 INFO [INFO] 2024-11-21 21:13:22,802 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-21 21:13:22,802 INFO [INFO] skip non existing resourceDirectory /tmp/tmpeify0gv6/src/main/resources 2024-11-21 21:13:22,802 INFO [INFO] 2024-11-21 21:13:22,802 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-21 21:13:22,802 INFO [INFO] No sources to compile 2024-11-21 21:13:22,802 INFO [INFO] 2024-11-21 21:13:22,802 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-21 21:13:22,802 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-21 21:13:22,802 INFO [INFO] 2024-11-21 21:13:22,802 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-21 21:13:22,802 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-21 21:13:22,802 INFO [INFO] 2024-11-21 21:13:22,802 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-21 21:13:22,802 INFO [INFO] compiling 38 Scala sources to /tmp/tmpeify0gv6/target/test-classes ... 2024-11-21 21:13:22,802 INFO [INFO] compile in 6.8 s 2024-11-21 21:13:22,802 INFO [INFO] 2024-11-21 21:13:22,802 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-21 21:13:22,802 INFO [INFO] 2024-11-21 21:13:22,802 INFO [INFO] 2024-11-21 21:13:22,802 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-21 21:13:22,802 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-21 21:13:22,802 INFO Gatling 3.13.1 is available! (you're using 3.12.0) 2024-11-21 21:13:22,802 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-21 21:13:22,802 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-21 21:13:22,802 INFO 2024-11-21 21:13:22,802 INFO ================================================================================ 2024-11-21 21:13:22,802 INFO 2024-11-21 20:13:01 GMT 5s elapsed 2024-11-21 21:13:22,802 INFO ---- Requests ------------------------------------------------------------------ 2024-11-21 21:13:22,802 INFO > Global (OK=62 KO=0 ) 2024-11-21 21:13:22,802 INFO > restLoginInitiate (OK=9 KO=0 ) 2024-11-21 21:13:22,802 INFO > restLoginUsernamePasswordCallback (OK=9 KO=0 ) 2024-11-21 21:13:22,802 INFO > User Authorize PKCE stage (OK=9 KO=0 ) 2024-11-21 21:13:22,802 INFO > User AccessToken PKCE stage (OK=8 KO=0 ) 2024-11-21 21:13:22,802 INFO > tokenUserinfo (OK=2 KO=0 ) 2024-11-21 21:13:22,802 INFO > loginTreeWorkflow_restLoginInitiate (OK=1 KO=0 ) 2024-11-21 21:13:22,802 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-11-21 21:13:22,802 INFO > Skip 2FA (OK=6 KO=0 ) 2024-11-21 21:13:22,802 INFO > GetUUid (OK=4 KO=0 ) 2024-11-21 21:13:22,802 INFO > GetOpenidConfig (OK=4 KO=0 ) 2024-11-21 21:13:22,802 INFO > CreatePolicy (OK=3 KO=0 ) 2024-11-21 21:13:22,802 INFO > RootUserinfo (OK=1 KO=0 ) 2024-11-21 21:13:22,802 INFO > getIdToken (OK=1 KO=0 ) 2024-11-21 21:13:22,802 INFO > getIdToken Redirect 1 (OK=1 KO=0 ) 2024-11-21 21:13:22,802 INFO > createOrganization (OK=1 KO=0 ) 2024-11-21 21:13:22,802 INFO > queryOrganization (OK=1 KO=0 ) 2024-11-21 21:13:22,802 INFO > deleteOrganization (OK=1 KO=0 ) 2024-11-21 21:13:22,802 INFO 2024-11-21 21:13:22,802 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-21 21:13:22,802 INFO [- ] 0% 2024-11-21 21:13:22,802 INFO waiting: 90320 / active: 6 / done: 4 2024-11-21 21:13:22,802 INFO ================================================================================ 2024-11-21 21:13:22,802 INFO 2024-11-21 21:13:22,802 INFO 2024-11-21 21:13:22,802 INFO ================================================================================ 2024-11-21 21:13:22,802 INFO 2024-11-21 20:13:06 GMT 10s elapsed 2024-11-21 21:13:22,802 INFO ---- Requests ------------------------------------------------------------------ 2024-11-21 21:13:22,802 INFO > Global (OK=331 KO=0 ) 2024-11-21 21:13:22,803 INFO > restLoginInitiate (OK=38 KO=0 ) 2024-11-21 21:13:22,803 INFO > restLoginUsernamePasswordCallback (OK=37 KO=0 ) 2024-11-21 21:13:22,803 INFO > User Authorize PKCE stage (OK=38 KO=0 ) 2024-11-21 21:13:22,803 INFO > User AccessToken PKCE stage (OK=38 KO=0 ) 2024-11-21 21:13:22,803 INFO > tokenUserinfo (OK=10 KO=0 ) 2024-11-21 21:13:22,803 INFO > loginTreeWorkflow_restLoginInitiate (OK=3 KO=0 ) 2024-11-21 21:13:22,803 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=3 KO=0 ) 2024-11-21 21:13:22,803 INFO > Skip 2FA (OK=25 KO=0 ) 2024-11-21 21:13:22,803 INFO > GetUUid (OK=14 KO=0 ) 2024-11-21 21:13:22,803 INFO > GetOpenidConfig (OK=17 KO=0 ) 2024-11-21 21:13:22,803 INFO > CreatePolicy (OK=14 KO=0 ) 2024-11-21 21:13:22,803 INFO > RootUserinfo (OK=30 KO=0 ) 2024-11-21 21:13:22,803 INFO > getIdToken (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > getIdToken Redirect 1 (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > createOrganization (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > queryOrganization (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > deleteOrganization (OK=1 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI-Post-Login - stage 1 (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI AM idFromSession (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI AM validateGoto (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI-Post-Login - stage 2 (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI Enduser appAuthHelper1 (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI Enduser appAuthHelper2 (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI-Post-Login - stage 3 (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI Enduser sessioncheck (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI IDM uiconfig (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI IDM version (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO > UI IDM privilege (OK=2 KO=0 ) 2024-11-21 21:13:22,803 INFO [...] 2024-11-21 21:13:22,810 INFO > UI AM endsession (OK=19056 KO=0 ) 2024-11-21 21:13:22,810 INFO > UI AM revoke2 (OK=19056 KO=0 ) 2024-11-21 21:13:22,810 INFO > ReadPolicy (OK=28760 KO=2 ) 2024-11-21 21:13:22,810 INFO > DeletePolicy (OK=28722 KO=4 ) 2024-11-21 21:13:22,810 INFO > restloginProgressiveProfileCallback (OK=554 KO=0 ) 2024-11-21 21:13:22,810 INFO > loginTreeWorkflow_restloginProgressiveProfileCallback (OK=111 KO=0 ) 2024-11-21 21:13:22,810 INFO ---- Errors -------------------------------------------------------------------- 2024-11-21 21:13:22,810 INFO > tokenUserinfo: No attribute named 'access_token' is defined 523 (94.40%) 2024-11-21 21:13:22,810 INFO > status.find.in([200, 209], 304), found 404 6 ( 1.08%) 2024-11-21 21:13:22,810 INFO > status.find.in([200, 209], 304), found 502 5 ( 0.90%) 2024-11-21 21:13:22,810 INFO > status.find.is(302), but actually found 502 5 ( 0.90%) 2024-11-21 21:13:22,810 INFO > RootUserinfo: No attribute named 'access_token' is defined 5 ( 0.90%) 2024-11-21 21:13:22,810 INFO > status.find.is(200), but actually found 502 4 ( 0.72%) 2024-11-21 21:13:22,810 INFO > status.find.in([200, 209], 304), found 409 4 ( 0.72%) 2024-11-21 21:13:22,810 INFO > userinfo: No attribute named 'access_token' is defined 1 ( 0.18%) 2024-11-21 21:13:22,810 INFO > UI AM revoke1: No attribute named 'access_token' is defined 1 ( 0.18%) 2024-11-21 21:13:22,810 INFO 2024-11-21 21:13:22,810 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-21 21:13:22,810 INFO [#########################################################################-] 98% 2024-11-21 21:13:22,810 INFO waiting: 847 / active: 97 / done: 89386 2024-11-21 21:13:22,810 INFO ================================================================================ 2024-11-21 21:13:22,810 INFO 2024-11-21 21:13:22,810 INFO 21:12:55.704 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-21 21:13:22,810 INFO 21:12:56.284 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request tokenUserinfo: No attribute named 'access_token' is defined 2024-11-21 21:13:22,810 INFO 2024-11-21 21:13:22,810 INFO ================================================================================ 2024-11-21 21:13:22,810 INFO 2024-11-21 21:12:56 GMT 3599s elapsed 2024-11-21 21:13:22,810 INFO ---- Requests ------------------------------------------------------------------ 2024-11-21 21:13:22,810 INFO > Global (OK=1250655 KO=24 ) 2024-11-21 21:13:22,810 INFO > restLoginInitiate (OK=86382 KO=0 ) 2024-11-21 21:13:22,810 INFO > restLoginUsernamePasswordCallback (OK=86379 KO=3 ) 2024-11-21 21:13:22,810 INFO > User Authorize PKCE stage (OK=104943 KO=5 ) 2024-11-21 21:13:22,810 INFO > User AccessToken PKCE stage (OK=104942 KO=1 ) 2024-11-21 21:13:22,810 INFO > tokenUserinfo (OK=18697 KO=0 ) 2024-11-21 21:13:22,810 INFO > loginTreeWorkflow_restLoginInitiate (OK=3209 KO=0 ) 2024-11-21 21:13:22,810 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=3208 KO=1 ) 2024-11-21 21:13:22,810 INFO > Skip 2FA (OK=48058 KO=0 ) 2024-11-21 21:13:22,810 INFO > GetUUid (OK=28828 KO=0 ) 2024-11-21 21:13:22,810 INFO > GetOpenidConfig (OK=86387 KO=0 ) 2024-11-21 21:13:22,810 INFO > CreatePolicy (OK=28823 KO=5 ) 2024-11-21 21:13:22,810 INFO > RootUserinfo (OK=64166 KO=0 ) 2024-11-21 21:13:22,810 INFO > getIdToken (OK=6392 KO=0 ) 2024-11-21 21:13:22,810 INFO > getIdToken Redirect 1 (OK=6391 KO=0 ) 2024-11-21 21:13:22,810 INFO > createOrganization (OK=6391 KO=0 ) 2024-11-21 21:13:22,810 INFO > queryOrganization (OK=6391 KO=0 ) 2024-11-21 21:13:22,811 INFO > deleteOrganization (OK=6391 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI-Post-Login - stage 1 (OK=19097 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=19097 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI AM idFromSession (OK=19097 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI AM validateGoto (OK=19096 KO=1 ) 2024-11-21 21:13:22,811 INFO > UI-Post-Login - stage 2 (OK=19096 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=19096 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI Enduser appAuthHelper1 (OK=19095 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI Enduser appAuthHelper2 (OK=19095 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI-Post-Login - stage 3 (OK=19095 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=19095 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI Enduser sessioncheck (OK=19095 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI IDM uiconfig (OK=19095 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI IDM version (OK=19095 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI IDM privilege (OK=19093 KO=2 ) 2024-11-21 21:13:22,811 INFO > UI IDM features (OK=19095 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI IDM login (OK=19095 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI IDM dashboard (OK=19094 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI IDM managed user schema (OK=19094 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI IDM user (OK=19094 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI IDM oidcToken (OK=19094 KO=0 ) 2024-11-21 21:13:22,811 INFO > userinfo (OK=19093 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI-Logout (OK=19094 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI-Logout Redirect 1 (OK=19093 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI AM revoke1 (OK=19091 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI AM endsession (OK=19092 KO=0 ) 2024-11-21 21:13:22,811 INFO > UI AM revoke2 (OK=19092 KO=0 ) 2024-11-21 21:13:22,811 INFO > ReadPolicy (OK=28794 KO=2 ) 2024-11-21 21:13:22,811 INFO > DeletePolicy (OK=28758 KO=4 ) 2024-11-21 21:13:22,811 INFO > restloginProgressiveProfileCallback (OK=556 KO=0 ) 2024-11-21 21:13:22,811 INFO > loginTreeWorkflow_restloginProgressiveProfileCallback (OK=111 KO=0 ) 2024-11-21 21:13:22,811 INFO ---- Errors -------------------------------------------------------------------- 2024-11-21 21:13:22,811 INFO > tokenUserinfo: No attribute named 'access_token' is defined 525 (94.42%) 2024-11-21 21:13:22,811 INFO > status.find.in([200, 209], 304), found 404 6 ( 1.08%) 2024-11-21 21:13:22,811 INFO > status.find.in([200, 209], 304), found 502 5 ( 0.90%) 2024-11-21 21:13:22,811 INFO > status.find.is(302), but actually found 502 5 ( 0.90%) 2024-11-21 21:13:22,811 INFO > RootUserinfo: No attribute named 'access_token' is defined 5 ( 0.90%) 2024-11-21 21:13:22,811 INFO > status.find.is(200), but actually found 502 4 ( 0.72%) 2024-11-21 21:13:22,811 INFO > status.find.in([200, 209], 304), found 409 4 ( 0.72%) 2024-11-21 21:13:22,811 INFO > userinfo: No attribute named 'access_token' is defined 1 ( 0.18%) 2024-11-21 21:13:22,811 INFO > UI AM revoke1: No attribute named 'access_token' is defined 1 ( 0.18%) 2024-11-21 21:13:22,811 INFO 2024-11-21 21:13:22,811 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-21 21:13:22,811 INFO [#########################################################################-] 99% 2024-11-21 21:13:22,811 INFO waiting: 739 / active: 85 / done: 89506 2024-11-21 21:13:22,811 INFO ================================================================================ 2024-11-21 21:13:22,811 INFO 2024-11-21 21:13:22,811 INFO [INFO] ------------------------------------------------------------------------ 2024-11-21 21:13:22,811 INFO [INFO] BUILD SUCCESS 2024-11-21 21:13:22,811 INFO [INFO] ------------------------------------------------------------------------ 2024-11-21 21:13:22,811 INFO [INFO] Total time: 01:00 h 2024-11-21 21:13:22,811 INFO [INFO] Finished at: 2024-11-21T21:12:56Z 2024-11-21 21:13:22,811 INFO [INFO] ------------------------------------------------------------------------ 2024-11-21 21:13:22,811 INFO 2024-11-21 21:12:56,932 INFO Gatling simulation run ended successfully 2024-11-21 21:13:22,811 INFO 2024-11-21 21:12:56,932 DEBUG No archive name provided. No archive will be created 2024-11-21 21:13:22,811 INFO ________________________________________________________________________________ [2024-11-21 21:13:22] benchmark-25 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-21 21:13:22,812 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/03_benchmark-25/stderr.txt is small (0 lines), printing all lines: 2024-11-21 21:13:22,812 DEBUG ----- output ----- 2024-11-21 21:13:22,812 DEBUG 2024-11-21 21:13:22,812 INFO ________________________________________________________________________________ [2024-11-21 21:13:22] benchmark-25 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-21 21:13:22] benchmark-25 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-21 21:13:22] benchmark-25 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped