--Task-- name: benchmark-40 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-10'] preceding_task: benchmark-10 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=selfr40-', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 40} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-11-18 17:18:14] benchmark-40 pre : N/A ________________________________________________________________________________ 2024-11-18 17:18:14 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-11-18 17:18:14 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/benchmark-40/stdout.txt Pod log : /results/orders/benchmark-40 2024-11-18 17:18:14,056 INFO 2024-11-18 17:18:14,056 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:14,056 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:14,333 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:14,333 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:14,333 DEBUG --- stdout --- 2024-11-18 17:18:14,333 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:14,333 DEBUG --- stderr --- 2024-11-18 17:18:14,333 DEBUG ________________________________________________________________________________ [2024-11-18 17:18:14] benchmark-40 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-11-18 17:18:14,334 INFO 2024-11-18 17:18:14,334 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:14,334 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:14,610 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:14,610 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:14,610 DEBUG --- stdout --- 2024-11-18 17:18:14,610 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:14,610 DEBUG --- stderr --- 2024-11-18 17:18:14,610 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-40" --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=selfr40- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=40 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2024-11-18 17:18:14] benchmark-40 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-11-18 17:18:14,610 INFO 2024-11-18 17:18:14,610 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:14,611 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:14,886 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:14,886 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:14,886 DEBUG --- stdout --- 2024-11-18 17:18:14,886 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:14,886 DEBUG --- stderr --- 2024-11-18 17:18:14,886 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/order.json { "name": "benchmark-40", "command": " /lodestar/lodestar.py run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@perf-benchmark\" --results-folder=\"/results/gatling/benchmark-40\" --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=selfr40- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=40 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-40" } ________________________________________________________________________________ [2024-11-18 17:18:14] benchmark-40 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 17:18:14,889 INFO 2024-11-18 17:18:14,889 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-11-18 17:18:14,889 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 17:18:15,100 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 17:18:15,100 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:15,100 DEBUG --- stdout --- 2024-11-18 17:18:15,100 DEBUG WAITING 2024-11-18 17:18:15,100 DEBUG --- stderr --- 2024-11-18 17:18:15,100 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-18 17:18:15] benchmark-40 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-11-18 17:18:15,101 INFO 2024-11-18 17:18:15,101 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:15,101 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:15,373 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:15,373 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:15,373 DEBUG --- stdout --- 2024-11-18 17:18:15,373 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:15,373 DEBUG --- stderr --- 2024-11-18 17:18:15,373 DEBUG 2024-11-18 17:18:15,373 INFO 2024-11-18 17:18:15,373 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/02_benchmark-40/order.json overseer-0-6644bb6697-rchxr:/results/orders/order.json 2024-11-18 17:18:15,373 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 17:18:16,704 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:16,704 DEBUG --- stdout --- 2024-11-18 17:18:16,705 DEBUG 2024-11-18 17:18:16,705 DEBUG --- stderr --- 2024-11-18 17:18:16,705 DEBUG Order has been successfully forwarded to overseer 2024-11-18 17:18:16 - INFO: Check if Gatling simulation started successfully 2024-11-18 17:18:16,705 INFO 2024-11-18 17:18:16,705 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:16,705 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 17:18:16,977 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 17:18:16,977 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:16,977 DEBUG --- stdout --- 2024-11-18 17:18:16,977 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 17:18:16,977 DEBUG --- stderr --- 2024-11-18 17:18:16,977 DEBUG 2024-11-18 17:18:16,978 INFO 2024-11-18 17:18:16,978 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-40/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2024-11-18 17:18:16,978 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-11-18 17:18:17,697 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 17:18:23,405 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2024-11-18 17:18:29,116 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-11-18 17:18:29,116 INFO [loop_until]: OK (rc = 0) 2024-11-18 17:18:29,116 DEBUG --- stdout --- 2024-11-18 17:18:29,116 DEBUG 2024-11-18 17:18:17,211 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpckc_638_ 2024-11-18 17:18:17,213 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-40" -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=selfr40- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=40 -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/tmpckc_638_/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/tmpckc_638_/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-18 17:18:29,116 DEBUG --- stderr --- 2024-11-18 17:18:29,116 DEBUG ________________________________________________________________________________ [2024-11-18 17:18:29] benchmark-40 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m. Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 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 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-18 18:18:37] benchmark-40 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-11-18 18:18:37,853 INFO 2024-11-18 18:18:37,853 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 18:18:37,853 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:38,145 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:38,145 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:38,145 DEBUG --- stdout --- 2024-11-18 18:18:38,145 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:38,145 DEBUG --- stderr --- 2024-11-18 18:18:38,146 DEBUG 2024-11-18 18:18:38,146 INFO 2024-11-18 18:18:38,146 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 18:18:38,146 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 18:18:38,961 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:38,961 DEBUG --- stdout --- 2024-11-18 18:18:38,961 DEBUG 2024-11-18 18:18:38,961 DEBUG --- stderr --- 2024-11-18 18:18:38,961 DEBUG Order ran during 1h ________________________________________________________________________________ [2024-11-18 18:18:38] benchmark-40 download_logs : Download individual order files ________________________________________________________________________________ 2024-11-18 18:18:38 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/order.json 2024-11-18 18:18:38,962 INFO 2024-11-18 18:18:38,962 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 18:18:38,962 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:39,231 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:39,231 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:39,231 DEBUG --- stdout --- 2024-11-18 18:18:39,231 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:39,231 DEBUG --- stderr --- 2024-11-18 18:18:39,231 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 18:18:39,232 INFO 2024-11-18 18:18:39,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-18 18:18:39,232 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:39,503 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:39,504 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:39,504 DEBUG --- stdout --- 2024-11-18 18:18:39,504 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:39,504 DEBUG --- stderr --- 2024-11-18 18:18:39,504 DEBUG 2024-11-18 18:18:39,505 INFO 2024-11-18 18:18:39,505 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-40/rc.txt" ]; then echo "/results/orders/benchmark-40/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-40/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-40/rc.txt file found" 2024-11-18 18:18:39,505 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 18:18:40,333 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 18:18:40,333 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:40,333 DEBUG --- stdout --- 2024-11-18 18:18:40,333 DEBUG /results/orders/benchmark-40/rc.txt file found 2024-11-18 18:18:40,333 DEBUG --- stderr --- 2024-11-18 18:18:40,333 DEBUG 2024-11-18 18:18:40,334 INFO 2024-11-18 18:18:40,334 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 18:18:40,334 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:40,608 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:40,608 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:40,608 DEBUG --- stdout --- 2024-11-18 18:18:40,608 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:40,608 DEBUG --- stderr --- 2024-11-18 18:18:40,608 DEBUG 2024-11-18 18:18:40,608 INFO 2024-11-18 18:18:40,608 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-40/rc.txt 2024-11-18 18:18:41,383 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 18:18:41,383 DEBUG --- stdout --- 2024-11-18 18:18:41,383 DEBUG 4 /results/orders/benchmark-40/rc.txt 2024-11-18 18:18:41,383 DEBUG --- stderr --- 2024-11-18 18:18:41,383 DEBUG 2024-11-18 18:18:41,383 INFO - Download file (size 4k) 2024-11-18 18:18:41,384 INFO 2024-11-18 18:18:41,384 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 18:18:41,384 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:41,660 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:41,660 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:41,660 DEBUG --- stdout --- 2024-11-18 18:18:41,660 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:41,660 DEBUG --- stderr --- 2024-11-18 18:18:41,660 DEBUG source : /results/orders/benchmark-40/rc.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/rc.txt 2024-11-18 18:18:41,661 INFO 2024-11-18 18:18:41,661 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 18:18:41,661 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:41,932 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:41,933 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:41,933 DEBUG --- stdout --- 2024-11-18 18:18:41,933 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:41,933 DEBUG --- stderr --- 2024-11-18 18:18:41,933 DEBUG 2024-11-18 18:18:41,933 INFO 2024-11-18 18:18:41,933 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-40/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/rc.txt 2024-11-18 18:18:41,933 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 18:18:42,674 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:42,674 DEBUG --- stdout --- 2024-11-18 18:18:42,674 DEBUG tar: Removing leading `/' from member names 2024-11-18 18:18:42,674 DEBUG --- stderr --- 2024-11-18 18:18:42,674 DEBUG 2024-11-18 18:18:42,674 INFO 2024-11-18 18:18:42,674 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 18:18:42,674 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:42,945 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:42,945 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:42,945 DEBUG --- stdout --- 2024-11-18 18:18:42,946 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:42,946 DEBUG --- stderr --- 2024-11-18 18:18:42,946 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 18:18:42,946 INFO 2024-11-18 18:18:42,946 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 18:18:42,946 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:43,218 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:43,218 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:43,218 DEBUG --- stdout --- 2024-11-18 18:18:43,218 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:43,218 DEBUG --- stderr --- 2024-11-18 18:18:43,218 DEBUG 2024-11-18 18:18:43,219 INFO 2024-11-18 18:18:43,219 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-40/stderr.txt" ]; then echo "/results/orders/benchmark-40/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-40/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-40/stderr.txt file found" 2024-11-18 18:18:43,219 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 18:18:44,018 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 18:18:44,018 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:44,018 DEBUG --- stdout --- 2024-11-18 18:18:44,018 DEBUG /results/orders/benchmark-40/stderr.txt file found 2024-11-18 18:18:44,018 DEBUG --- stderr --- 2024-11-18 18:18:44,018 DEBUG 2024-11-18 18:18:44,019 INFO 2024-11-18 18:18:44,019 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 18:18:44,019 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:44,291 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:44,291 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:44,291 DEBUG --- stdout --- 2024-11-18 18:18:44,291 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:44,291 DEBUG --- stderr --- 2024-11-18 18:18:44,291 DEBUG 2024-11-18 18:18:44,291 INFO 2024-11-18 18:18:44,292 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-40/stderr.txt 2024-11-18 18:18:45,073 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 18:18:45,073 DEBUG --- stdout --- 2024-11-18 18:18:45,074 DEBUG 0 /results/orders/benchmark-40/stderr.txt 2024-11-18 18:18:45,074 DEBUG --- stderr --- 2024-11-18 18:18:45,074 DEBUG 2024-11-18 18:18:45,074 INFO - Download file (size 0k) 2024-11-18 18:18:45,074 INFO 2024-11-18 18:18:45,074 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 18:18:45,074 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:45,347 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:45,347 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:45,347 DEBUG --- stdout --- 2024-11-18 18:18:45,347 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:45,347 DEBUG --- stderr --- 2024-11-18 18:18:45,347 DEBUG source : /results/orders/benchmark-40/stderr.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/stderr.txt 2024-11-18 18:18:45,348 INFO 2024-11-18 18:18:45,348 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 18:18:45,348 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:45,620 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:45,620 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:45,620 DEBUG --- stdout --- 2024-11-18 18:18:45,620 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:45,620 DEBUG --- stderr --- 2024-11-18 18:18:45,620 DEBUG 2024-11-18 18:18:45,621 INFO 2024-11-18 18:18:45,621 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-40/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/stderr.txt 2024-11-18 18:18:45,621 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 18:18:46,365 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:46,365 DEBUG --- stdout --- 2024-11-18 18:18:46,365 DEBUG tar: Removing leading `/' from member names 2024-11-18 18:18:46,365 DEBUG --- stderr --- 2024-11-18 18:18:46,365 DEBUG 2024-11-18 18:18:46,365 INFO 2024-11-18 18:18:46,365 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 18:18:46,365 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:46,640 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:46,640 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:46,640 DEBUG --- stdout --- 2024-11-18 18:18:46,640 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:46,640 DEBUG --- stderr --- 2024-11-18 18:18:46,640 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 18:18:46,640 INFO 2024-11-18 18:18:46,640 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 18:18:46,640 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:46,913 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:46,913 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:46,913 DEBUG --- stdout --- 2024-11-18 18:18:46,913 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:46,913 DEBUG --- stderr --- 2024-11-18 18:18:46,913 DEBUG 2024-11-18 18:18:46,914 INFO 2024-11-18 18:18:46,914 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-40/stdout.txt" ]; then echo "/results/orders/benchmark-40/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-40/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-40/stdout.txt file found" 2024-11-18 18:18:46,914 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 18:18:47,688 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 18:18:47,688 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:47,688 DEBUG --- stdout --- 2024-11-18 18:18:47,688 DEBUG /results/orders/benchmark-40/stdout.txt file found 2024-11-18 18:18:47,688 DEBUG --- stderr --- 2024-11-18 18:18:47,688 DEBUG 2024-11-18 18:18:47,689 INFO 2024-11-18 18:18:47,689 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 18:18:47,689 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:47,961 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:47,962 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:47,962 DEBUG --- stdout --- 2024-11-18 18:18:47,962 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:47,962 DEBUG --- stderr --- 2024-11-18 18:18:47,962 DEBUG 2024-11-18 18:18:47,962 INFO 2024-11-18 18:18:47,962 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-40/stdout.txt 2024-11-18 18:18:48,745 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 18:18:48,745 DEBUG --- stdout --- 2024-11-18 18:18:48,745 DEBUG 3844 /results/orders/benchmark-40/stdout.txt 2024-11-18 18:18:48,745 DEBUG --- stderr --- 2024-11-18 18:18:48,745 DEBUG 2024-11-18 18:18:48,746 INFO - Download file (size 3844k) 2024-11-18 18:18:48,746 INFO 2024-11-18 18:18:48,746 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 18:18:48,746 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:49,017 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:49,017 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:49,017 DEBUG --- stdout --- 2024-11-18 18:18:49,017 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:49,017 DEBUG --- stderr --- 2024-11-18 18:18:49,017 DEBUG source : /results/orders/benchmark-40/stdout.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/stdout.txt 2024-11-18 18:18:49,017 INFO 2024-11-18 18:18:49,017 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 18:18:49,017 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:49,290 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:49,290 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:49,290 DEBUG --- stdout --- 2024-11-18 18:18:49,290 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:49,290 DEBUG --- stderr --- 2024-11-18 18:18:49,290 DEBUG 2024-11-18 18:18:49,291 INFO 2024-11-18 18:18:49,291 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-40/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/stdout.txt 2024-11-18 18:18:49,291 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 18:18:50,591 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:50,591 DEBUG --- stdout --- 2024-11-18 18:18:50,591 DEBUG tar: Removing leading `/' from member names 2024-11-18 18:18:50,591 DEBUG --- stderr --- 2024-11-18 18:18:50,591 DEBUG ________________________________________________________________________________ [2024-11-18 18:18:50] benchmark-40 show_cmd : Display order command ________________________________________________________________________________ 2024-11-18 18:18:50,591 INFO 2024-11-18 18:18:50,591 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 18:18:50,591 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:50,864 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:50,864 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:50,864 DEBUG --- stdout --- 2024-11-18 18:18:50,864 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:50,864 DEBUG --- stderr --- 2024-11-18 18:18:50,864 DEBUG Checking pod: overseer-0-6644bb6697-rchxr for file exists, if not wait. 2024-11-18 18:18:50,865 INFO 2024-11-18 18:18:50,865 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 18:18:50,865 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:51,136 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:51,136 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:51,136 DEBUG --- stdout --- 2024-11-18 18:18:51,136 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:51,136 DEBUG --- stderr --- 2024-11-18 18:18:51,136 DEBUG 2024-11-18 18:18:51,137 INFO 2024-11-18 18:18:51,137 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-40/cmd.txt" ]; then echo "/results/orders/benchmark-40/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-40/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-40/cmd.txt file found" 2024-11-18 18:18:51,137 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-11-18 18:18:51,912 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-11-18 18:18:51,912 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:51,912 DEBUG --- stdout --- 2024-11-18 18:18:51,912 DEBUG /results/orders/benchmark-40/cmd.txt file found 2024-11-18 18:18:51,912 DEBUG --- stderr --- 2024-11-18 18:18:51,912 DEBUG 2024-11-18 18:18:51,912 INFO 2024-11-18 18:18:51,912 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 18:18:51,912 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:52,183 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:52,183 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:52,183 DEBUG --- stdout --- 2024-11-18 18:18:52,183 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:52,183 DEBUG --- stderr --- 2024-11-18 18:18:52,183 DEBUG 2024-11-18 18:18:52,183 INFO 2024-11-18 18:18:52,183 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-40/cmd.txt 2024-11-18 18:18:52,970 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-11-18 18:18:52,970 DEBUG --- stdout --- 2024-11-18 18:18:52,970 DEBUG 4 /results/orders/benchmark-40/cmd.txt 2024-11-18 18:18:52,970 DEBUG --- stderr --- 2024-11-18 18:18:52,970 DEBUG 2024-11-18 18:18:52,970 INFO - Download file (size 4k) 2024-11-18 18:18:52,971 INFO 2024-11-18 18:18:52,971 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-11-18 18:18:52,971 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:53,242 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:53,242 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:53,242 DEBUG --- stdout --- 2024-11-18 18:18:53,242 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:53,242 DEBUG --- stderr --- 2024-11-18 18:18:53,242 DEBUG source : /results/orders/benchmark-40/cmd.txt @ overseer-0-6644bb6697-rchxr destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/cmd.txt 2024-11-18 18:18:53,242 INFO 2024-11-18 18:18:53,242 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 18:18:53,242 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-11-18 18:18:53,516 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-11-18 18:18:53,516 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:53,516 DEBUG --- stdout --- 2024-11-18 18:18:53,516 DEBUG overseer-0-6644bb6697-rchxr 2024-11-18 18:18:53,516 DEBUG --- stderr --- 2024-11-18 18:18:53,516 DEBUG 2024-11-18 18:18:53,516 INFO 2024-11-18 18:18:53,516 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6644bb6697-rchxr:/results/orders/benchmark-40/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/cmd.txt 2024-11-18 18:18:53,516 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-11-18 18:18:54,283 INFO [loop_until]: OK (rc = 0) 2024-11-18 18:18:54,283 DEBUG --- stdout --- 2024-11-18 18:18:54,283 DEBUG tar: Removing leading `/' from member names 2024-11-18 18:18:54,283 DEBUG --- stderr --- 2024-11-18 18:18:54,283 DEBUG 2024-11-18 18:18:54,284 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/cmd.txt is small (1 lines), printing all lines: 2024-11-18 18:18:54,284 DEBUG ----- output ----- 2024-11-18 18:18:54,284 DEBUG /lodestar/lodestar.py run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@perf-benchmark" --results-folder="/results/gatling/benchmark-40" --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=selfr40- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=40 -Duser_password=Pa_ssw0rd" 2024-11-18 18:18:54,284 INFO ________________________________________________________________________________ [2024-11-18 18:18:54] benchmark-40 show_rc : Display rc order ________________________________________________________________________________ 2024-11-18 18:18:54,284 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/rc.txt is small (1 lines), printing all lines: 2024-11-18 18:18:54,284 DEBUG ----- output ----- 2024-11-18 18:18:54,284 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-11-18 18:18:54,284 INFO ________________________________________________________________________________ [2024-11-18 18:18:54] benchmark-40 show_stdout : Display stdout order ________________________________________________________________________________ 2024-11-18 18:18:54,289 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/02_benchmark-40/stdout.txt is 49450 lines): 2024-11-18 18:18:54,289 DEBUG ----- output ----- 2024-11-18 18:18:54,289 INFO 2024-11-18 17:18:17,211 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpckc_638_ 2024-11-18 18:18:54,289 INFO 2024-11-18 17:18:17,213 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-40" -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=selfr40- -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=40 -Duser_password=**** 2024-11-18 18:18:54,289 INFO [INFO] Scanning for projects... 2024-11-18 18:18:54,289 INFO [INFO] 2024-11-18 18:18:54,289 INFO [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- 2024-11-18 18:18:54,289 INFO [INFO] Building pyrock-gatling-simulation 3.12.0 2024-11-18 18:18:54,289 INFO [INFO] from pom.xml 2024-11-18 18:18:54,289 INFO [INFO] --------------------------------[ jar ]--------------------------------- 2024-11-18 18:18:54,289 INFO [INFO] 2024-11-18 18:18:54,289 INFO [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> 2024-11-18 18:18:54,289 INFO [INFO] 2024-11-18 18:18:54,289 INFO [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- 2024-11-18 18:18:54,289 INFO [INFO] skip non existing resourceDirectory /tmp/tmpckc_638_/src/main/resources 2024-11-18 18:18:54,289 INFO [INFO] 2024-11-18 18:18:54,289 INFO [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- 2024-11-18 18:18:54,289 INFO [INFO] No sources to compile 2024-11-18 18:18:54,289 INFO [INFO] 2024-11-18 18:18:54,290 INFO [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- 2024-11-18 18:18:54,290 INFO [INFO] Copying 2 resources from src/test/resources to target/test-classes 2024-11-18 18:18:54,290 INFO [INFO] 2024-11-18 18:18:54,290 INFO [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- 2024-11-18 18:18:54,290 INFO [INFO] Nothing to compile - all classes are up to date. 2024-11-18 18:18:54,290 INFO [INFO] 2024-11-18 18:18:54,290 INFO [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- 2024-11-18 18:18:54,290 INFO [INFO] compiling 38 Scala sources to /tmp/tmpckc_638_/target/test-classes ... 2024-11-18 18:18:54,290 INFO [INFO] compile in 6.8 s 2024-11-18 18:18:54,290 INFO [INFO] 2024-11-18 18:18:54,290 INFO [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< 2024-11-18 18:18:54,290 INFO [INFO] 2024-11-18 18:18:54,290 INFO [INFO] 2024-11-18 18:18:54,290 INFO [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- 2024-11-18 18:18:54,290 INFO [INFO] Running simulation com.forgerock.pyrock.Loadtest. 2024-11-18 18:18:54,290 INFO Gatling 3.13.1 is available! (you're using 3.12.0) 2024-11-18 18:18:54,290 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-11-18 18:18:54,290 INFO Simulation com.forgerock.pyrock.Loadtest started... 2024-11-18 18:18:54,290 INFO 2024-11-18 18:18:54,290 INFO ================================================================================ 2024-11-18 18:18:54,290 INFO 2024-11-18 17:18:33 GMT 5s elapsed 2024-11-18 18:18:54,290 INFO ---- Requests ------------------------------------------------------------------ 2024-11-18 18:18:54,290 INFO > Global (OK=143 KO=0 ) 2024-11-18 18:18:54,290 INFO > restLoginInitiate (OK=15 KO=0 ) 2024-11-18 18:18:54,290 INFO > restLoginUsernamePasswordCallback (OK=15 KO=0 ) 2024-11-18 18:18:54,290 INFO > Skip 2FA (OK=10 KO=0 ) 2024-11-18 18:18:54,290 INFO > User Authorize PKCE stage (OK=16 KO=0 ) 2024-11-18 18:18:54,290 INFO > User AccessToken PKCE stage (OK=16 KO=0 ) 2024-11-18 18:18:54,290 INFO > RootUserinfo (OK=6 KO=0 ) 2024-11-18 18:18:54,290 INFO > GetUUid (OK=5 KO=0 ) 2024-11-18 18:18:54,290 INFO > GetOpenidConfig (OK=5 KO=0 ) 2024-11-18 18:18:54,290 INFO > CreatePolicy (OK=5 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI-Post-Login - stage 1 (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI-Post-Login - stage 1 Redirect 1 (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > loginTreeWorkflow_restLoginInitiate (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI AM validateGoto (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI AM idFromSession (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI-Post-Login - stage 2 (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI-Post-Login - stage 2 Redirect 1 (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI Enduser appAuthHelper2 (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI Enduser appAuthHelper1 (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI-Post-Login - stage 3 (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI-Post-Login - stage 3 Redirect 1 (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI Enduser sessioncheck (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI IDM uiconfig (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI IDM version (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI IDM login (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI IDM privilege (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI IDM features (OK=2 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI IDM dashboard (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI IDM managed user schema (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI IDM user (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > getIdToken (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI IDM oidcToken (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > getIdToken Redirect 1 (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > userinfo (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI-Logout (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > createOrganization (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI-Logout Redirect 1 (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > queryOrganization (OK=1 KO=0 ) 2024-11-18 18:18:54,290 INFO > UI AM revoke1 (OK=1 KO=0 ) 2024-11-18 18:18:54,291 INFO > UI AM revoke2 (OK=1 KO=0 ) 2024-11-18 18:18:54,291 INFO > UI AM endsession (OK=1 KO=0 ) 2024-11-18 18:18:54,291 INFO > deleteOrganization (OK=1 KO=0 ) 2024-11-18 18:18:54,291 INFO > tokenUserinfo (OK=1 KO=0 ) 2024-11-18 18:18:54,291 INFO 2024-11-18 18:18:54,291 INFO ---- idc.benchmark.Mix2024 ----------------------------------------------------- 2024-11-18 18:18:54,291 INFO [- ] 0% 2024-11-18 18:18:54,291 INFO waiting: 144280 / active: 12 / done: 4 2024-11-18 18:18:54,291 INFO ================================================================================ 2024-11-18 18:18:54,291 INFO 2024-11-18 18:18:54,291 INFO 2024-11-18 18:18:54,291 INFO ================================================================================ 2024-11-18 18:18:54,291 INFO 2024-11-18 17:18:38 GMT 10s elapsed 2024-11-18 18:18:54,291 INFO ---- Requests ------------------------------------------------------------------ 2024-11-18 18:18:54,291 INFO > Global (OK=670 KO=0 ) 2024-11-18 18:18:54,291 INFO > restLoginInitiate (OK=64 KO=0 ) 2024-11-18 18:18:54,291 INFO > restLoginUsernamePasswordCallback (OK=63 KO=0 ) 2024-11-18 18:18:54,291 INFO > Skip 2FA (OK=40 KO=0 ) 2024-11-18 18:18:54,291 INFO > User Authorize PKCE stage (OK=71 KO=0 ) 2024-11-18 18:18:54,291 INFO > User AccessToken PKCE stage (OK=69 KO=0 ) 2024-11-18 18:18:54,291 INFO > RootUserinfo (OK=37 KO=0 ) 2024-11-18 18:18:54,291 INFO [...] 2024-11-18 18:18:54,298 INFO Session: 2024-11-18 18:18:54,298 INFO Session(idc.benchmark.Mix2024,143058,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, username -> user.47907, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@6310c46c, fake-user-agent -> Mozilla/5.0 (iPhone; CPU iPhone OS 10_3 like Mac OS X) AppleWebKit/602.1.50 (KHTML, like Gecko) CriOS/56.0.2924.75 Mobile/14E5239e Safari/602.1, password -> Pa_ssw0rd, fake-ip -> 121.124.82.81, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@6c34aad),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007901a8699970@1e26f1b0,io.netty.channel.epoll.EpollEventLoop@14f5da2c) 2024-11-18 18:18:54,298 INFO ========================= 2024-11-18 18:18:54,298 INFO HTTP request: 2024-11-18 18:18:54,298 INFO POST https://openam-perf-benchmark.forgeblocks.com/am/json/realms/root/realms/alpha/authenticate?authIndexType=service&authIndexValue=Login 2024-11-18 18:18:54,298 INFO headers: 2024-11-18 18:18:54,298 INFO accept: application/json 2024-11-18 18:18:54,298 INFO content-type: application/json 2024-11-18 18:18:54,298 INFO user-agent: Mozilla/5.0 (iPhone; CPU iPhone OS 10_3 like Mac OS X) AppleWebKit/602.1.50 (KHTML, like Gecko) CriOS/56.0.2924.75 Mobile/14E5239e Safari/602.1 2024-11-18 18:18:54,298 INFO X-Forwarded-For: 121.124.82.81 2024-11-18 18:18:54,298 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 18:18:54,298 INFO content-length: 0 2024-11-18 18:18:54,298 INFO body:StringChunksRequestBody{charset=UTF-8, content=} 2024-11-18 18:18:54,298 INFO ========================= 2024-11-18 18:18:54,298 INFO HTTP response: 2024-11-18 18:18:54,298 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 18:18:54,298 INFO 18:18:28.010 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 143026: j.i.IOException: Premature close 2024-11-18 18:18:54,298 INFO 18:18:28.010 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 18:18:54,298 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 18:18:54,298 INFO Request: 2024-11-18 18:18:54,298 INFO UI IDM login: KO j.i.IOException: Premature close 2024-11-18 18:18:54,298 INFO ========================= 2024-11-18 18:18:54,298 INFO Session: 2024-11-18 18:18:54,298 INFO Session(idc.benchmark.Mix2024,143026,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJhNTQ0Nzc4MS1jNDQ5LTRiZGQtOGQ2MC04NzM5MTAzNGUzZTciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZTY1ZTU0MzYtMzcwYS00MzI0LTg5ZmUtNDJhMjY3ZjljYzAyLTkxNzMxNTY2Iiwic3VibmFtZSI6ImE1NDQ3NzgxLWM0NDktNGJkZC04ZDYwLTg3MzkxMDM0ZTNlNyIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IldYMGdieFVWRXlJY2RtekdiNFlDVlNfSzhnTSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTk1MzkwNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTk1MzkwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE5NTc1MDcsImlhdCI6MTczMTk1MzkwNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiOERUU3Y5X3E2X1dfTUV3Y0o2NWVPVlBsUWJ3In0.aJQTB2oi_oq05d6FSm6ymKnPcDCU0GpzDF-oeT3Pl_4, username -> user.40776, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJhNTQ0Nzc4MS1jNDQ5LTRiZGQtOGQ2MC04NzM5MTAzNGUzZTciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTkxMzcyODM2Iiwic3VibmFtZSI6ImE1NDQ3NzgxLWM0NDktNGJkZC04ZDYwLTg3MzkxMDM0ZTNlNyIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6InpTU21VTjJnQ2Y0S0xIUVo0MlN2ZkxSblFCdyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTk1MzkwNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTk1MzkwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE5NTc1MDcsImlhdCI6MTczMTk1MzkwNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoibEtIUjNCbjdtYm9janc4dlFsWE82Znp4a3NjIn0.rjNQpWJv3AXAsSk01e7y-2oQXhwoPEEzLaVZlTc3GhQ, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@75521c87, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> jqzE_f5pBVYE4aZYL5KlVf3PeKg, callbacks -> {"tokenId":"zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731953907416), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731953907416))), user_uuid -> a5447781-c449-4bdd-8d60-87391034e3e7, password -> Pa_ssw0rd, challenge -> QSrXU3a_9Fih8iVDEz5j_GuK60jRY-td6QHccYC4k0M, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 56.147.42.66, verifier -> MzczNDgyNDMxMjE1OTgwNDQ3MA, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7b837a33, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@57d95aa4, tokenId -> zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Linux; Android 8.0.0; Pixel 2 Build/OPD3.170816.012) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.85 Mobile Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007901a8699970@1e26f1b0,io.netty.channel.epoll.EpollEventLoop@14f5da2c) 2024-11-18 18:18:54,298 INFO ========================= 2024-11-18 18:18:54,298 INFO HTTP request: 2024-11-18 18:18:54,298 INFO POST https://openam-perf-benchmark.forgeblocks.com/openidm/authentication?_action=login 2024-11-18 18:18:54,298 INFO headers: 2024-11-18 18:18:54,298 INFO user-agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 12_5_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/105.0.0.0 Safari/537.36 2024-11-18 18:18:54,298 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 18:18:54,298 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJhNTQ0Nzc4MS1jNDQ5LTRiZGQtOGQ2MC04NzM5MTAzNGUzZTciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTkxMzcyODM2Iiwic3VibmFtZSI6ImE1NDQ3NzgxLWM0NDktNGJkZC04ZDYwLTg3MzkxMDM0ZTNlNyIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6InpTU21VTjJnQ2Y0S0xIUVo0MlN2ZkxSblFCdyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTk1MzkwNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTk1MzkwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE5NTc1MDcsImlhdCI6MTczMTk1MzkwNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoibEtIUjNCbjdtYm9janc4dlFsWE82Znp4a3NjIn0.rjNQpWJv3AXAsSk01e7y-2oQXhwoPEEzLaVZlTc3GhQ 2024-11-18 18:18:54,298 INFO Accept-Api-Version: 2024-11-18 18:18:54,298 INFO accept: */* 2024-11-18 18:18:54,298 INFO cookie: amlbcookie=01; 9b75a55744995d0=zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 18:18:54,298 INFO origin: https://openam-perf-benchmark.forgeblocks.com 2024-11-18 18:18:54,298 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 18:18:54,299 INFO content-length: 0 2024-11-18 18:18:54,299 INFO cookies: 2024-11-18 18:18:54,299 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 18:18:54,299 INFO 9b75a55744995d0=zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 18:18:54,299 INFO ========================= 2024-11-18 18:18:54,299 INFO HTTP response: 2024-11-18 18:18:54,299 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 18:18:54,299 INFO 18:18:28.010 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM version' failed for user 143026: j.i.IOException: Premature close 2024-11-18 18:18:54,299 INFO 18:18:28.010 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 18:18:54,299 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 18:18:54,299 INFO Request: 2024-11-18 18:18:54,299 INFO UI IDM version: KO j.i.IOException: Premature close 2024-11-18 18:18:54,299 INFO ========================= 2024-11-18 18:18:54,299 INFO Session: 2024-11-18 18:18:54,299 INFO Session(idc.benchmark.Mix2024,143026,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJhNTQ0Nzc4MS1jNDQ5LTRiZGQtOGQ2MC04NzM5MTAzNGUzZTciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZTY1ZTU0MzYtMzcwYS00MzI0LTg5ZmUtNDJhMjY3ZjljYzAyLTkxNzMxNTY2Iiwic3VibmFtZSI6ImE1NDQ3NzgxLWM0NDktNGJkZC04ZDYwLTg3MzkxMDM0ZTNlNyIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IldYMGdieFVWRXlJY2RtekdiNFlDVlNfSzhnTSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTk1MzkwNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTk1MzkwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE5NTc1MDcsImlhdCI6MTczMTk1MzkwNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiOERUU3Y5X3E2X1dfTUV3Y0o2NWVPVlBsUWJ3In0.aJQTB2oi_oq05d6FSm6ymKnPcDCU0GpzDF-oeT3Pl_4, username -> user.40776, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJhNTQ0Nzc4MS1jNDQ5LTRiZGQtOGQ2MC04NzM5MTAzNGUzZTciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTkxMzcyODM2Iiwic3VibmFtZSI6ImE1NDQ3NzgxLWM0NDktNGJkZC04ZDYwLTg3MzkxMDM0ZTNlNyIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6InpTU21VTjJnQ2Y0S0xIUVo0MlN2ZkxSblFCdyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTk1MzkwNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTk1MzkwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE5NTc1MDcsImlhdCI6MTczMTk1MzkwNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoibEtIUjNCbjdtYm9janc4dlFsWE82Znp4a3NjIn0.rjNQpWJv3AXAsSk01e7y-2oQXhwoPEEzLaVZlTc3GhQ, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@75521c87, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> jqzE_f5pBVYE4aZYL5KlVf3PeKg, callbacks -> {"tokenId":"zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731953907416), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731953907416))), user_uuid -> a5447781-c449-4bdd-8d60-87391034e3e7, password -> Pa_ssw0rd, challenge -> QSrXU3a_9Fih8iVDEz5j_GuK60jRY-td6QHccYC4k0M, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 56.147.42.66, verifier -> MzczNDgyNDMxMjE1OTgwNDQ3MA, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7b837a33, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@57d95aa4, tokenId -> zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Linux; Android 8.0.0; Pixel 2 Build/OPD3.170816.012) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.85 Mobile Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007901a8699970@1e26f1b0,io.netty.channel.epoll.EpollEventLoop@14f5da2c) 2024-11-18 18:18:54,299 INFO ========================= 2024-11-18 18:18:54,299 INFO HTTP request: 2024-11-18 18:18:54,299 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/version 2024-11-18 18:18:54,299 INFO headers: 2024-11-18 18:18:54,299 INFO user-agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 12_5_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/105.0.0.0 Safari/537.36 2024-11-18 18:18:54,299 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 18:18:54,299 INFO Accept-Api-Version: 2024-11-18 18:18:54,299 INFO accept: */* 2024-11-18 18:18:54,299 INFO cookie: amlbcookie=01; 9b75a55744995d0=zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 18:18:54,299 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 18:18:54,299 INFO cookies: 2024-11-18 18:18:54,299 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 18:18:54,299 INFO 9b75a55744995d0=zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 18:18:54,299 INFO ========================= 2024-11-18 18:18:54,299 INFO HTTP response: 2024-11-18 18:18:54,299 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 18:18:54,299 INFO 18:18:28.010 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM features' failed for user 143026: j.i.IOException: Premature close 2024-11-18 18:18:54,299 INFO 18:18:28.010 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - 2024-11-18 18:18:54,299 INFO >>>>>>>>>>>>>>>>>>>>>>>>>> 2024-11-18 18:18:54,299 INFO Request: 2024-11-18 18:18:54,299 INFO UI IDM features: KO j.i.IOException: Premature close 2024-11-18 18:18:54,299 INFO ========================= 2024-11-18 18:18:54,299 INFO Session: 2024-11-18 18:18:54,299 INFO Session(idc.benchmark.Mix2024,143026,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJhNTQ0Nzc4MS1jNDQ5LTRiZGQtOGQ2MC04NzM5MTAzNGUzZTciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZTY1ZTU0MzYtMzcwYS00MzI0LTg5ZmUtNDJhMjY3ZjljYzAyLTkxNzMxNTY2Iiwic3VibmFtZSI6ImE1NDQ3NzgxLWM0NDktNGJkZC04ZDYwLTg3MzkxMDM0ZTNlNyIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6IldYMGdieFVWRXlJY2RtekdiNFlDVlNfSzhnTSIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTk1MzkwNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbIm9wZW5pZCIsImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTk1MzkwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE5NTc1MDcsImlhdCI6MTczMTk1MzkwNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoiOERUU3Y5X3E2X1dfTUV3Y0o2NWVPVlBsUWJ3In0.aJQTB2oi_oq05d6FSm6ymKnPcDCU0GpzDF-oeT3Pl_4, username -> user.40776, access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJhNTQ0Nzc4MS1jNDQ5LTRiZGQtOGQ2MC04NzM5MTAzNGUzZTciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTkxMzcyODM2Iiwic3VibmFtZSI6ImE1NDQ3NzgxLWM0NDktNGJkZC04ZDYwLTg3MzkxMDM0ZTNlNyIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6InpTU21VTjJnQ2Y0S0xIUVo0MlN2ZkxSblFCdyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTk1MzkwNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTk1MzkwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE5NTc1MDcsImlhdCI6MTczMTk1MzkwNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoibEtIUjNCbjdtYm9janc4dlFsWE82Znp4a3NjIn0.rjNQpWJv3AXAsSk01e7y-2oQXhwoPEEzLaVZlTc3GhQ, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@75521c87, gatling.http.referer -> https://openam-perf-benchmark.forgeblocks.com/am/, authcode -> jqzE_f5pBVYE4aZYL5KlVf3PeKg, callbacks -> {"tokenId":"zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731953907416), CookieKey(9b75a55744995d0,openam-perf-benchmark.forgeblocks.com,/) -> StoredCookie(9b75a55744995d0=zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1731953907416))), user_uuid -> a5447781-c449-4bdd-8d60-87391034e3e7, password -> Pa_ssw0rd, challenge -> QSrXU3a_9Fih8iVDEz5j_GuK60jRY-td6QHccYC4k0M, gatling.http.cache.baseUrl -> https://openam-perf-benchmark.forgeblocks.com:443, fake-ip -> 56.147.42.66, verifier -> MzczNDgyNDMxMjE1OTgwNDQ3MA, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7b837a33, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@57d95aa4, tokenId -> zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (Linux; Android 8.0.0; Pixel 2 Build/OPD3.170816.012) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.85 Mobile Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x00007901a8699970@1e26f1b0,io.netty.channel.epoll.EpollEventLoop@14f5da2c) 2024-11-18 18:18:54,299 INFO ========================= 2024-11-18 18:18:54,299 INFO HTTP request: 2024-11-18 18:18:54,299 INFO GET https://openam-perf-benchmark.forgeblocks.com/openidm/info/features?_queryFilter=true 2024-11-18 18:18:54,299 INFO headers: 2024-11-18 18:18:54,299 INFO user-agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 12_5_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/105.0.0.0 Safari/537.36 2024-11-18 18:18:54,299 INFO referer: https://openam-perf-benchmark.forgeblocks.com/am/ 2024-11-18 18:18:54,299 INFO Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJhNTQ0Nzc4MS1jNDQ5LTRiZGQtOGQ2MC04NzM5MTAzNGUzZTciLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNGY3MjdhMWUtNThjYS00ZjViLThlNzUtYWE2MjA4ZTlmZDIzLTkxMzcyODM2Iiwic3VibmFtZSI6ImE1NDQ3NzgxLWM0NDktNGJkZC04ZDYwLTg3MzkxMDM0ZTNlNyIsImlzcyI6Imh0dHBzOi8vb3BlbmFtLXBlcmYtYmVuY2htYXJrLmZvcmdlYmxvY2tzLmNvbTo0NDMvYW0vb2F1dGgyL3JlYWxtcy9yb290L3JlYWxtcy9hbHBoYSIsInRva2VuTmFtZSI6ImFjY2Vzc190b2tlbiIsInRva2VuX3R5cGUiOiJCZWFyZXIiLCJhdXRoR3JhbnRJZCI6InpTU21VTjJnQ2Y0S0xIUVo0MlN2ZkxSblFCdyIsIm5vbmNlIjoiMTIzNCIsImNsaWVudF9pZCI6ImVuZFVzZXJVSUNsaWVudCIsImF1ZCI6ImVuZFVzZXJVSUNsaWVudCIsIm5iZiI6MTczMTk1MzkwNywiZ3JhbnRfdHlwZSI6ImF1dGhvcml6YXRpb25fY29kZSIsInNjb3BlIjpbImZyOmlkbToqIl0sImF1dGhfdGltZSI6MTczMTk1MzkwNywicmVhbG0iOiIvYWxwaGEiLCJleHAiOjE3MzE5NTc1MDcsImlhdCI6MTczMTk1MzkwNywiZXhwaXJlc19pbiI6MzYwMCwianRpIjoibEtIUjNCbjdtYm9janc4dlFsWE82Znp4a3NjIn0.rjNQpWJv3AXAsSk01e7y-2oQXhwoPEEzLaVZlTc3GhQ 2024-11-18 18:18:54,299 INFO Accept-Api-Version: 2024-11-18 18:18:54,299 INFO accept: */* 2024-11-18 18:18:54,299 INFO cookie: amlbcookie=01; 9b75a55744995d0=zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..* 2024-11-18 18:18:54,299 INFO host: openam-perf-benchmark.forgeblocks.com 2024-11-18 18:18:54,299 INFO cookies: 2024-11-18 18:18:54,299 INFO amlbcookie=01, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 18:18:54,299 INFO 9b75a55744995d0=zMUNRwTolijSHqgtf1BtJBu1CoM.*AAJTSQACMDIAAlNLABxIb1Jxc1N1QzNqMngrNExnUWZJY0RUQkZTYWs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-perf-benchmark.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 2024-11-18 18:18:54,299 INFO ========================= 2024-11-18 18:18:54,299 INFO HTTP response: 2024-11-18 18:18:54,299 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-11-18 18:18:54,299 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 18:18:54,299 INFO [INFO] BUILD SUCCESS 2024-11-18 18:18:54,299 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 18:18:54,299 INFO [INFO] Total time: 01:00 h 2024-11-18 18:18:54,299 INFO [INFO] Finished at: 2024-11-18T18:18:28Z 2024-11-18 18:18:54,300 INFO [INFO] ------------------------------------------------------------------------ 2024-11-18 18:18:54,300 INFO 2024-11-18 18:18:28,102 INFO Gatling simulation run ended successfully 2024-11-18 18:18:54,300 INFO 2024-11-18 18:18:28,102 DEBUG No archive name provided. No archive will be created 2024-11-18 18:18:54,300 INFO ________________________________________________________________________________ [2024-11-18 18:18:54] benchmark-40 show_stderr : Display stderr order ________________________________________________________________________________ 2024-11-18 18:18:54,300 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/02_benchmark-40/stderr.txt is small (0 lines), printing all lines: 2024-11-18 18:18:54,300 DEBUG ----- output ----- 2024-11-18 18:18:54,300 DEBUG 2024-11-18 18:18:54,300 INFO ________________________________________________________________________________ [2024-11-18 18:18:54] benchmark-40 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 18:18:54] benchmark-40 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-11-18 18:18:54] benchmark-40 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped