--Task--
name: benchmark-10
enabled: True
class_name: GatlingTask
source_name: overseer-0
source_namespace: >default<
target_name: am
target_namespace: >default<
start: 0.0
stop: None
timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates below
loop: False
interval: None
dependencies: []
wait_for: []
preceding_task: None
options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10-', 'testCase': 'idc.benchmark.CH2023', 'testMode': 'open', 'throughput': 10}
group_name: None
Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock
2025-09-18 19:16:27,057 INFO
2025-09-18 19:16:27,057 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
2025-09-18 19:16:27,057 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2025-09-18 19:16:27,363 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2025-09-18 19:16:27,363 INFO [loop_until]: OK (rc = 0)
2025-09-18 19:16:27,363 DEBUG --- stdout ---
2025-09-18 19:16:27,363 DEBUG overseer-0-57d79d468-khs5t
2025-09-18 19:16:27,363 DEBUG --- stderr ---
2025-09-18 19:16:27,363 DEBUG
2025-09-18 19:16:27,363 INFO Lodestar assumed that full stack is deployed so will set all products host URLs in Gatling system properties
2025-09-18 19:16:27 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds)
2025-09-18 19:16:27 - INFO: timeout was calculated based on simulation duration parameter
2025-09-18 19:16:27 - INFO: Interval for this Task has changed to 2m (120 seconds)
2025-09-18 19:16:27 - INFO: interval was set to Task default because it was unset, based on self.timeout value because task is allowed to stop by itself
Pod log file : /results/orders/benchmark-10/stdout.txt
Pod log : /results/orders/benchmark-10
2025-09-18 19:16:28 - INFO: Get overseer pod resources requests memory
2025-09-18 19:16:28,154 INFO
2025-09-18 19:16:28,154 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-57d79d468-khs5t --output jsonpath={.spec.containers[].resources.requests.memory}
2025-09-18 19:16:28,154 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2025-09-18 19:16:28,460 INFO [loop_until]: OK (rc = 0)
2025-09-18 19:16:28,460 DEBUG --- stdout ---
2025-09-18 19:16:28,460 DEBUG 4Gi
2025-09-18 19:16:28,460 DEBUG --- stderr ---
2025-09-18 19:16:28,460 DEBUG
________________________________________________________________________________
[2025-09-18 19:16:28] benchmark-10 print_command : Display the command to be forwarded to overseer as an order
________________________________________________________________________________
/lodestar/lodestar.py perf run-gatling --mvn-quiet --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.CH2023@perf-haproxy" --results-folder="/results/gatling/benchmark-10" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-haproxy.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-perf-haproxy.forgeblocks.com -Dlogin_cookie=d0d18cac672abbc -Dnum_users=10000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10- -Drealm=alpha -DtestCase=idc.benchmark.CH2023 -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd"
________________________________________________________________________________
[2025-09-18 19:16:28] benchmark-10 create_order : Create order for overseer with the command to run
________________________________________________________________________________
Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/order.json
{
"name": "benchmark-10",
"command": " /lodestar/lodestar.py perf run-gatling --mvn-quiet --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.CH2023@perf-haproxy\" --results-folder=\"/results/gatling/benchmark-10\" --no-reports --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx4G\" --simulation-system-properties=\"-Dam_host=openam-perf-haproxy.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-perf-haproxy.forgeblocks.com -Dlogin_cookie=d0d18cac672abbc -Dnum_users=10000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10- -Drealm=alpha -DtestCase=idc.benchmark.CH2023 -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd\"",
"out-dir": "/results/orders/benchmark-10"
}
________________________________________________________________________________
[2025-09-18 19:16:28] benchmark-10 loop_until_ready :
Wait for preceding task to finish if it uses same Overseer as a source,
then loop for max_time until Overseer pod status is READY to accept order
:param max_time: periodically check overseer status for this many seconds at maximum
:param interval: interval between two periodical checks
:return:
________________________________________________________________________________
2025-09-18 19:16:28,469 INFO
2025-09-18 19:16:28,469 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING"
2025-09-18 19:16:28,469 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0]
2025-09-18 19:16:28,734 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2025-09-18 19:16:28,734 INFO [loop_until]: OK (rc = 0)
2025-09-18 19:16:28,734 DEBUG --- stdout ---
2025-09-18 19:16:28,734 DEBUG WAITING
2025-09-18 19:16:28,734 DEBUG --- stderr ---
2025-09-18 19:16:28,734 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 27 0 --:--:-- --:--:-- --:--:-- 27
________________________________________________________________________________
[2025-09-18 19:16:28] benchmark-10 start_order : Send the order to the overseer pod
________________________________________________________________________________
2025-09-18 19:16:28,735 INFO
2025-09-18 19:16:28,735 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
2025-09-18 19:16:28,735 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2025-09-18 19:16:29,028 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2025-09-18 19:16:29,028 INFO [loop_until]: OK (rc = 0)
2025-09-18 19:16:29,028 DEBUG --- stdout ---
2025-09-18 19:16:29,028 DEBUG overseer-0-57d79d468-khs5t
2025-09-18 19:16:29,028 DEBUG --- stderr ---
2025-09-18 19:16:29,028 DEBUG
2025-09-18 19:16:29,028 INFO
2025-09-18 19:16:29,028 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/order.json overseer-0-57d79d468-khs5t:/results/orders/order.json
2025-09-18 19:16:29,028 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2025-09-18 19:16:29,974 INFO [loop_until]: OK (rc = 0)
2025-09-18 19:16:29,974 DEBUG --- stdout ---
2025-09-18 19:16:29,974 DEBUG
2025-09-18 19:16:29,974 DEBUG --- stderr ---
2025-09-18 19:16:29,974 DEBUG
Order has been successfully forwarded to overseer
2025-09-18 19:16:29 - INFO: Check if Gatling simulation started successfully
2025-09-18 19:16:29,974 INFO
2025-09-18 19:16:29,974 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-57d79d468-khs5t -- head --lines 5000 /results/orders/benchmark-10/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..."
2025-09-18 19:16:29,974 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0]
2025-09-18 19:16:30,511 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry
2025-09-18 19:16:36,075 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry
2025-09-18 19:16:41,620 INFO [loop_until]: Function succeeded after 11s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry
2025-09-18 19:16:47,172 INFO [loop_until]: Function succeeded after 17s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry
2025-09-18 19:16:52,766 INFO [loop_until]: Function succeeded after 22s (rc=0) - expected pattern found
2025-09-18 19:16:52,766 INFO [loop_until]: OK (rc = 0)
2025-09-18 19:16:52,766 DEBUG --- stdout ---
2025-09-18 19:16:52,766 DEBUG 2025-09-18 20:16:32,420 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp4qiarrsr
2025-09-18 20:16:32,424 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode --quiet -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-10" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-haproxy.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-perf-haproxy.forgeblocks.com -Dlogin_cookie=d0d18cac672abbc -Dnum_users=10000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10- -Drealm=alpha -DtestCase=idc.benchmark.CH2023 -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd
Gatling 3.13.5 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...
2025-09-18 19:16:52,766 DEBUG --- stderr ---
2025-09-18 19:16:52,766 DEBUG
________________________________________________________________________________
[2025-09-18 19:16:52] benchmark-10 wait_stop_by_itself : Wait for the order to stop by itself
________________________________________________________________________________
Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m.
Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING
Order is running since 2m, retry
Order is running since 4m, retry
Order is running since 6m, retry
Order is running since 8m 01s, retry
Order is running since 10m 01s, retry
Order is running since 12m 01s, retry
Order is running since 14m 02s, retry
Order is running since 16m 02s, retry
Order is running since 18m 02s, retry
Order is running since 20m 02s, retry
Order is running since 22m 03s, retry
Order is running since 24m 03s, retry
Order is running since 26m 03s, retry
Order is running since 28m 03s, 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 04s, retry
Order is running since 38m 05s, retry
Order is running since 40m 05s, retry
Order is running since 42m 05s, 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 06s, retry
Order is running since 52m 07s, retry
Order is running since 54m 07s, retry
Order is running since 56m 07s, retry
Order is running since 58m 07s, retry
PASS : Order stopped by itself after 1h and before timeout (1h 12m)
________________________________________________________________________________
[2025-09-18 20:17:01] benchmark-10 stop_order : Stop the order on overseer pod
________________________________________________________________________________
2025-09-18 20:17:01,000 INFO
2025-09-18 20:17:01,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
2025-09-18 20:17:01,000 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2025-09-18 20:17:01,312 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2025-09-18 20:17:01,312 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:01,312 DEBUG --- stdout ---
2025-09-18 20:17:01,312 DEBUG overseer-0-57d79d468-khs5t
2025-09-18 20:17:01,312 DEBUG --- stderr ---
2025-09-18 20:17:01,312 DEBUG
2025-09-18 20:17:01,312 INFO
2025-09-18 20:17:01,312 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-57d79d468-khs5t -- rm -f /results/orders/order.json
2025-09-18 20:17:01,312 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2025-09-18 20:17:01,925 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:01,925 DEBUG --- stdout ---
2025-09-18 20:17:01,925 DEBUG
2025-09-18 20:17:01,925 DEBUG --- stderr ---
2025-09-18 20:17:01,925 DEBUG
Order ran during 1h
________________________________________________________________________________
[2025-09-18 20:17:01] benchmark-10 download_logs : Download individual order files
________________________________________________________________________________
2025-09-18 20:17:01 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/order.json
2025-09-18 20:17:01,926 INFO
2025-09-18 20:17:01,926 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
2025-09-18 20:17:01,926 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2025-09-18 20:17:02,216 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2025-09-18 20:17:02,216 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:02,216 DEBUG --- stdout ---
2025-09-18 20:17:02,216 DEBUG overseer-0-57d79d468-khs5t
2025-09-18 20:17:02,216 DEBUG --- stderr ---
2025-09-18 20:17:02,216 DEBUG
Checking if file exists on pod 'overseer-0-57d79d468-khs5t', wait max 3 minutes if not.
2025-09-18 20:17:02,217 INFO
2025-09-18 20:17:02,217 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-57d79d468-khs5t -- bash -c 'if [ -f "/results/orders/benchmark-10/rc.txt" ]; then echo "/results/orders/benchmark-10/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-10/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-10/rc.txt file found"
2025-09-18 20:17:02,217 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2025-09-18 20:17:02,756 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2025-09-18 20:17:02,756 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:02,756 DEBUG --- stdout ---
2025-09-18 20:17:02,756 DEBUG /results/orders/benchmark-10/rc.txt file found
2025-09-18 20:17:02,756 DEBUG --- stderr ---
2025-09-18 20:17:02,756 DEBUG
2025-09-18 20:17:02,757 INFO
2025-09-18 20:17:02,757 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-57d79d468-khs5t -- du -k /results/orders/benchmark-10/rc.txt | grep "^\d+ .+$"
2025-09-18 20:17:02,757 INFO [loop_until]: (max_time=240, interval=10, expected_rc=[0]
2025-09-18 20:17:03,303 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2025-09-18 20:17:03,303 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:03,303 DEBUG --- stdout ---
2025-09-18 20:17:03,303 DEBUG 4 /results/orders/benchmark-10/rc.txt
2025-09-18 20:17:03,303 DEBUG --- stderr ---
2025-09-18 20:17:03,303 DEBUG
- Download file (size 4kB)
source : /results/orders/benchmark-10/rc.txt @ overseer-0-57d79d468-khs5t
destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/rc.txt
2025-09-18 20:17:03,304 INFO
2025-09-18 20:17:03,304 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-57d79d468-khs5t:/results/orders/benchmark-10/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/rc.txt
2025-09-18 20:17:03,304 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2025-09-18 20:17:03,839 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:03,839 DEBUG --- stdout ---
2025-09-18 20:17:03,839 DEBUG tar: Removing leading `/' from member names
2025-09-18 20:17:03,839 DEBUG --- stderr ---
2025-09-18 20:17:03,839 DEBUG
2025-09-18 20:17:03,840 INFO
2025-09-18 20:17:03,840 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
2025-09-18 20:17:03,840 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2025-09-18 20:17:04,132 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2025-09-18 20:17:04,132 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:04,132 DEBUG --- stdout ---
2025-09-18 20:17:04,132 DEBUG overseer-0-57d79d468-khs5t
2025-09-18 20:17:04,132 DEBUG --- stderr ---
2025-09-18 20:17:04,133 DEBUG
Checking if file exists on pod 'overseer-0-57d79d468-khs5t', wait max 3 minutes if not.
2025-09-18 20:17:04,133 INFO
2025-09-18 20:17:04,133 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-57d79d468-khs5t -- bash -c 'if [ -f "/results/orders/benchmark-10/stderr.txt" ]; then echo "/results/orders/benchmark-10/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-10/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-10/stderr.txt file found"
2025-09-18 20:17:04,133 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2025-09-18 20:17:04,673 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2025-09-18 20:17:04,673 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:04,673 DEBUG --- stdout ---
2025-09-18 20:17:04,673 DEBUG /results/orders/benchmark-10/stderr.txt file found
2025-09-18 20:17:04,673 DEBUG --- stderr ---
2025-09-18 20:17:04,673 DEBUG
2025-09-18 20:17:04,673 INFO
2025-09-18 20:17:04,673 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-57d79d468-khs5t -- du -k /results/orders/benchmark-10/stderr.txt | grep "^\d+ .+$"
2025-09-18 20:17:04,673 INFO [loop_until]: (max_time=240, interval=10, expected_rc=[0]
2025-09-18 20:17:05,224 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2025-09-18 20:17:05,224 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:05,224 DEBUG --- stdout ---
2025-09-18 20:17:05,224 DEBUG 0 /results/orders/benchmark-10/stderr.txt
2025-09-18 20:17:05,224 DEBUG --- stderr ---
2025-09-18 20:17:05,224 DEBUG
- Download file (size 0B)
source : /results/orders/benchmark-10/stderr.txt @ overseer-0-57d79d468-khs5t
destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stderr.txt
2025-09-18 20:17:05,225 INFO
2025-09-18 20:17:05,225 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-57d79d468-khs5t:/results/orders/benchmark-10/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stderr.txt
2025-09-18 20:17:05,225 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2025-09-18 20:17:05,764 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:05,764 DEBUG --- stdout ---
2025-09-18 20:17:05,764 DEBUG tar: Removing leading `/' from member names
2025-09-18 20:17:05,764 DEBUG --- stderr ---
2025-09-18 20:17:05,764 DEBUG
2025-09-18 20:17:05,764 INFO
2025-09-18 20:17:05,764 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
2025-09-18 20:17:05,764 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2025-09-18 20:17:06,063 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2025-09-18 20:17:06,063 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:06,063 DEBUG --- stdout ---
2025-09-18 20:17:06,063 DEBUG overseer-0-57d79d468-khs5t
2025-09-18 20:17:06,063 DEBUG --- stderr ---
2025-09-18 20:17:06,063 DEBUG
Checking if file exists on pod 'overseer-0-57d79d468-khs5t', wait max 3 minutes if not.
2025-09-18 20:17:06,064 INFO
2025-09-18 20:17:06,064 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-57d79d468-khs5t -- bash -c 'if [ -f "/results/orders/benchmark-10/stdout.txt" ]; then echo "/results/orders/benchmark-10/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-10/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-10/stdout.txt file found"
2025-09-18 20:17:06,064 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2025-09-18 20:17:06,620 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2025-09-18 20:17:06,620 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:06,620 DEBUG --- stdout ---
2025-09-18 20:17:06,620 DEBUG /results/orders/benchmark-10/stdout.txt file found
2025-09-18 20:17:06,620 DEBUG --- stderr ---
2025-09-18 20:17:06,620 DEBUG
2025-09-18 20:17:06,620 INFO
2025-09-18 20:17:06,620 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-57d79d468-khs5t -- du -k /results/orders/benchmark-10/stdout.txt | grep "^\d+ .+$"
2025-09-18 20:17:06,620 INFO [loop_until]: (max_time=240, interval=10, expected_rc=[0]
2025-09-18 20:17:07,343 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2025-09-18 20:17:07,343 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:07,343 DEBUG --- stdout ---
2025-09-18 20:17:07,343 DEBUG 57936 /results/orders/benchmark-10/stdout.txt
2025-09-18 20:17:07,343 DEBUG --- stderr ---
2025-09-18 20:17:07,343 DEBUG
- Download file (size 57.936MB)
source : /results/orders/benchmark-10/stdout.txt @ overseer-0-57d79d468-khs5t
destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stdout.txt
2025-09-18 20:17:07,344 INFO
2025-09-18 20:17:07,344 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-57d79d468-khs5t:/results/orders/benchmark-10/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stdout.txt
2025-09-18 20:17:07,344 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2025-09-18 20:17:09,742 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:09,742 DEBUG --- stdout ---
2025-09-18 20:17:09,742 DEBUG tar: Removing leading `/' from member names
2025-09-18 20:17:09,742 DEBUG --- stderr ---
2025-09-18 20:17:09,742 DEBUG
________________________________________________________________________________
[2025-09-18 20:17:09] benchmark-10 show_cmd : Display order command
________________________________________________________________________________
2025-09-18 20:17:09,743 INFO
2025-09-18 20:17:09,743 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
2025-09-18 20:17:09,743 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2025-09-18 20:17:10,024 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2025-09-18 20:17:10,024 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:10,024 DEBUG --- stdout ---
2025-09-18 20:17:10,024 DEBUG overseer-0-57d79d468-khs5t
2025-09-18 20:17:10,024 DEBUG --- stderr ---
2025-09-18 20:17:10,024 DEBUG
Checking if file exists on pod 'overseer-0-57d79d468-khs5t', wait max 3 minutes if not.
2025-09-18 20:17:10,025 INFO
2025-09-18 20:17:10,025 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-57d79d468-khs5t -- bash -c 'if [ -f "/results/orders/benchmark-10/cmd.txt" ]; then echo "/results/orders/benchmark-10/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-10/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-10/cmd.txt file found"
2025-09-18 20:17:10,025 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2025-09-18 20:17:10,573 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2025-09-18 20:17:10,573 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:10,573 DEBUG --- stdout ---
2025-09-18 20:17:10,573 DEBUG /results/orders/benchmark-10/cmd.txt file found
2025-09-18 20:17:10,574 DEBUG --- stderr ---
2025-09-18 20:17:10,574 DEBUG
2025-09-18 20:17:10,574 INFO
2025-09-18 20:17:10,574 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-57d79d468-khs5t -- du -k /results/orders/benchmark-10/cmd.txt | grep "^\d+ .+$"
2025-09-18 20:17:10,574 INFO [loop_until]: (max_time=240, interval=10, expected_rc=[0]
2025-09-18 20:17:11,111 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2025-09-18 20:17:11,111 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:11,111 DEBUG --- stdout ---
2025-09-18 20:17:11,111 DEBUG 4 /results/orders/benchmark-10/cmd.txt
2025-09-18 20:17:11,111 DEBUG --- stderr ---
2025-09-18 20:17:11,111 DEBUG
- Download file (size 4kB)
source : /results/orders/benchmark-10/cmd.txt @ overseer-0-57d79d468-khs5t
destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/cmd.txt
2025-09-18 20:17:11,112 INFO
2025-09-18 20:17:11,112 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-57d79d468-khs5t:/results/orders/benchmark-10/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/cmd.txt
2025-09-18 20:17:11,112 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2025-09-18 20:17:11,656 INFO [loop_until]: OK (rc = 0)
2025-09-18 20:17:11,656 DEBUG --- stdout ---
2025-09-18 20:17:11,656 DEBUG
2025-09-18 20:17:11,656 DEBUG --- stderr ---
2025-09-18 20:17:11,656 DEBUG
2025-09-18 20:17:11,656 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/cmd.txt is small (1 lines), printing all lines:
2025-09-18 20:17:11,656 INFO ----- output -----
/lodestar/lodestar.py perf run-gatling --mvn-quiet --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.CH2023@perf-haproxy" --results-folder="/results/gatling/benchmark-10" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-perf-haproxy.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-perf-haproxy.forgeblocks.com -Dlogin_cookie=d0d18cac672abbc -Dnum_users=10000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10- -Drealm=alpha -DtestCase=idc.benchmark.CH2023 -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd"
________________________________________________________________________________
[2025-09-18 20:17:11] benchmark-10 show_rc : Display rc order
________________________________________________________________________________
2025-09-18 20:17:11,656 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/rc.txt is small (1 lines), printing all lines:
2025-09-18 20:17:11,656 INFO ----- output -----
CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15])
________________________________________________________________________________
[2025-09-18 20:17:11] benchmark-10 show_stdout : Display stdout order
________________________________________________________________________________
2025-09-18 20:17:11,704 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stdout.txt is 243572 lines):
2025-09-18 20:17:11,766 INFO ----- output -----
2025-09-18 20:16:32,420 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmp4qiarrsr
2025-09-18 20:16:32,424 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode --quiet -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/benchmark-10" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-haproxy.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-perf-haproxy.forgeblocks.com -Dlogin_cookie=d0d18cac672abbc -Dnum_users=10000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Dprefix=self10- -Drealm=alpha -DtestCase=idc.benchmark.CH2023 -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd
Gatling 3.13.5 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...
================================================================================
2025-09-18 19:16:55 GMT 4s elapsed
---- Requests ------------------------------------------------------------------
> Global (OK=16 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> restLoginInitiate (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> restLoginUsernamePasswordCallback (OK=3 KO=0 )
> Skip 2FA (OK=3 KO=0 )
> User Authorize PKCE stage (OK=1 KO=0 )
> User AccessToken PKCE stage (OK=1 KO=0 )
---- idc.benchmark.CH2023 ------------------------------------------------------
[- ] 0%
waiting: 35885 / active: 4 / done: 0
================================================================================
================================================================================
2025-09-18 19:17:00 GMT 9s elapsed
---- Requests ------------------------------------------------------------------
> Global (OK=201 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=7 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> restLoginInitiate (OK=12 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> restLoginUsernamePasswordCallback (OK=12 KO=0 )
> Skip 2FA (OK=9 KO=0 )
> User Authorize PKCE stage (OK=11 KO=0 )
> User AccessToken PKCE stage (OK=11 KO=0 )
> RootUserinfo (OK=22 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=8 KO=0 )
> tokenUserinfo (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=8 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4 KO=0 )
> selfService: login existing user and patch its telNum... (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=3 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1 KO=0 )
> getIdToken (OK=1 KO=0 )
> getIdToken Redirect 1 (OK=1 KO=0 )
> createOrganization (OK=1 KO=0 )
> queryOrganization (OK=1 KO=0 )
> deleteOrganization (OK=1 KO=0 )
---- idc.benchmark.CH2023 ------------------------------------------------------
[- ] 0%
waiting: 35873 / active: 9 / done: 7
================================================================================
20:17:01.361 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'registrationCallback' failed for user 18: jsonPath($.tokenId).find.exists, found nothing
20:17:01.372 [DEBUG] i.g.h.e.r.DefaultStatsProcessor -
>>>>>>>>>>>>>>>>>>>>>>>>>>
Request:
registrationCallback: KO jsonPath($.tokenId).find.exists, found nothing
=========================
Session:
Session(idc.benchmark.CH2023,18,HashMap(gatling.http.cache.baseUrl -> https://openam-perf-haproxy.forgeblocks.com:443, username -> self10-user.10000001, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@10e57656, fake-user-agent -> Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_2) AppleWebKit/601.3.9 (KHTML, like Gecko) Version/9.0.2 Safari/601.3.9, callbacks -> {"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiJhOGQ3OGFlYS03ODZjLTRhODEtYWI3My0zMzYyNjBhOTdkYzciLCJhdXRoSW5kZXhWYWx1ZSI6IlJlZ2lzdHJhdGlvbiIsIm90ayI6InNkY212NTVpY2ljMW45dWM1dGdiNTluaW1jIiwiYXV0aEluZGV4VHlwZSI6InNlcnZpY2UiLCJyZWFsbSI6Ii9hbHBoYSIsInNlc3Npb25JZCI6IipBQUpUU1FBQ01ESUFCSFI1Y0dVQUNFcFhWRjlCVlZSSUFBSlRNUUFDTURFLipleUowZVhBaU9pSktWMVFpTENKamRIa2lPaUpLVjFRaUxDSmhiR2NpT2lKSVV6STFOaUo5LlpYbEtNR1ZZUVdsUGFVcExWakZSYVV4RFNteGliVTFwVDJsS1FrMVVTVFJSTUVwRVRGVm9WRTFxVlRKSmFYZHBXVmQ0YmtscWIybGFSMng1U1c0d0xpNUdPRVl5VGpKMlRXNXZXVE5qTUVZNFYyUjBSeTFCTGtOS2VqQjBiM2RxTFROeVZHNUpjRkpPVVZGUlIyZG5iMlpyVVRKUlJUUjZYM0J2V2pkV2RqWm9jM2xMTkZoMlIzaGxiM1JmUlVKRFlWTlZjbWN0VWs1SmVERkVhbVJNZGpObWJtTmFPWEpUV1hsb2IzUTRaVkptYjBkVk1XTk9hbDlEVjAwek1HbHRiVGxtUTIxeFVERlRMVWw1Ukc5YVVFMVJkRTlNWW1aMFFVOUpjMlZ3WW5KbE4zWnFlVGxPUTI5eVVuWnpRVkpLTVRONGVXSkxWRFZOTW1aRFFXTkNkVnBwZUVOSVdHOVBVRVZFWWpKUmVqRklTVlpJUVZST04zSnRSMXBTVkdobVQyUnVXREV3YTFOaWNXUndjMmRuZFU5RVEwWkdOUzAxVEdaMGJuQXRRa0ZVYVdvNGRrRjFkRmRwVnkxR1JrdFphMEZPYm5GR1FrcERaRVZ6UmxkRUxXbHpVVkZmTjFnd1JuaFBaMkUwV0dGR1JXUk1NWGxHZFUxMFMzZFZOemREVGxSelgwbHpMVzlsYmxCNlZVeExVRGxuVW5KV1ZGQTJWVlpOYzAweVkyazVhWEpFWVVSMWNWRmhabnBVYlVWTmIwbDBXR0YxT1ZOb2JGOTRNVmxKZVhkaFNHOVFNRE5oUmxsdFdFRjNVVmxsY0hOMWJXcFpSamszY1VaT1dEUTJka0pHVVVweVRHVnRhM0psYld4MVNrNURkSE5WZUMwM1NsTjJUM1I2VUZkaFNHSnlha1Y2TlVGeU5IaFRkRFExYlZsSGMxcDJjbkJEZG5KelVrOUZabXBhTUVkVFJGVmtjMGhrTUVWU1duSktSbXQwTUdsSlpURkdaMVp0Ymt4RVUwOU5TV3B3TlZNeGIydGhPR00zY3pGRVFrdEZZbUpKYlVkbU0wVlFMVVZPYUdOUk5VOU1WV1JRWW1SR2FteFBSWGd6UW1KaFdrVkdZWFZyZVZkSk0yTnNNemw1VUdSb2VEbFROa1pXWTBoQ1pIcGFVMDFvWDI4MWJFMDRXV2RHTVdjM2VUZzFNR1o0VjJwUmF5MWxjM2xTYzFOUVFsOVdSRzVKZFVkd1pGaEhhWHB5UTFBNVlVcHVjMFJ4WlhOclFqSlpZWGhrZDFsdmNGbG1ObEpFYkdVemFIcFBSWFZNTlVRM1owSllNM2wxZUZnMllrcE5XQzAwTFRWblZtWk5YMHRQYVZwb1UzZFVOUzF5VlhKSmRuTnVkRjlRTFZSSlNuUk9USGhZZEVoeVFrOVVaWE5FYTFaeE5UWjBUVlZ2WldGak1qQklOSGxhWHpWYVF6RnNUWEV6YVRkWWFWY3diSE5oTkZwVmVqbEpNMDFKVlcweE4zRnJlVWxKV0dGeFVYZGxkMmh3UVY5R1VYbEJPRVJqZUhseGVqWk9aV3huTjNNMk0wODFjWGh0TWw5dFpXbFRRbU16WWtwVFJHTjBRMDVNTW1oZldWQjFkbTVhVW5jMk4xTTJOamg1WkdaamVHRlhiR1YzTGxoMFRXMXVNM1pHVnpjMlVXdFBORGxmVm05SmIyYy5sZkZ0SmNRYUFlQmR2ZWNLWnNUTWNmcUY2dTFiYmtaZXU3YlVoM1JEcTNNIiwiZXhwIjoxNzU4MjIzMzIxLCJpYXQiOjE3NTgyMjMwMjF9.56BJUccTsYtnWzTXe9E4pzzIYS_m68g-8AxlpzB4EL0","callbacks":[{"type":"ValidatedCreateUsernameCallback","output":[{"name":"policies","value":{"policyRequirements":["REQUIRED","MIN_LENGTH","VALID_TYPE","VALID_USERNAME","CANNOT_CONTAIN_CHARACTERS","MAX_LENGTH"],"fallbackPolicies":null,"name":"userName","policies":[{"policyRequirements":["REQUIRED"],"policyId":"required"},{"policyRequirements":["REQUIRED"],"policyId":"not-empty"},{"policyRequirements":["MIN_LENGTH"],"policyId":"minimum-length","params":{"minLength":1}},{"policyRequirements":["VALID_TYPE"],"policyId":"valid-type","params":{"types":["string"]}},{"policyId":"valid-username","policyRequirements":["VALID_USERNAME"]},{"params":{"forbiddenChars":["/"]},"policyId":"cannot-contain-characters","policyRequirements":["CANNOT_CONTAIN_CHARACTERS"]},{"params":{"minLength":1},"policyId":"minimum-length","policyRequirements":["MIN_LENGTH"]},{"params":{"maxLength":255},"policyId":"maximum-length","policyRequirements":["MAX_LENGTH"]}],"conditionalPolicies":null}},{"name":"failedPolicies","value":[]},{"name":"validateOnly","value":false},{"name":"prompt","value":"Username"}],"input":[{"name":"IDToken1","value":"self10-user.10000001"},{"name":"IDToken1validateOnly","value":false}],"_id":0},{"type":"StringAttributeInputCallback","output":[{"name":"name","value":"givenName"},{"name":"prompt","value":"First Name"},{"name":"required","value":true},{"name":"policies","value":{"policyRequirements":["REQUIRED","VALID_TYPE"],"fallbackPolicies":null,"name":"givenName","policies":[{"policyRequirements":["REQUIRED"],"policyId":"required"},{"policyRequirements":["VALID_TYPE"],"policyId":"valid-type","params":{"types":["string"]}}],"conditionalPolicies":null}},{"name":"failedPolicies","value":[]},{"name":"validateOnly","value":false},{"name":"value","value":""}],"input":[{"name":"IDToken2","value":"self10-user.10000001"},{"name":"IDToken2validateOnly","value":false}],"_id":1},{"type":"StringAttributeInputCallback","output":[{"name":"name","value":"sn"},{"name":"prompt","value":"Last Name"},{"name":"required","value":true},{"name":"policies","value":{"policyRequirements":["REQUIRED","VALID_TYPE"],"fallbackPolicies":null,"name":"sn","policies":[{"policyRequirements":["REQUIRED"],"policyId":"required"},{"policyRequirements":["VALID_TYPE"],"policyId":"valid-type","params":{"types":["string"]}}],"conditionalPolicies":null}},{"name":"failedPolicies","value":[]},{"name":"validateOnly","value":false},{"name":"value","value":""}],"input":[{"name":"IDToken3","value":"self10-user.10000001"},{"name":"IDToken3validateOnly","value":false}],"_id":2},{"type":"StringAttributeInputCallback","output":[{"name":"name","value":"mail"},{"name":"prompt","value":"Email Address"},{"name":"required","value":true},{"name":"policies","value":{"policyRequirements":["REQUIRED","VALID_TYPE","VALID_EMAIL_ADDRESS_FORMAT"],"fallbackPolicies":null,"name":"mail","policies":[{"policyRequirements":["REQUIRED"],"policyId":"required"},{"policyRequirements":["VALID_TYPE"],"policyId":"valid-type","params":{"types":["string"]}},{"policyId":"valid-email-address-format","policyRequirements":["VALID_EMAIL_ADDRESS_FORMAT"]}],"conditionalPolicies":null}},{"name":"failedPolicies","value":[]},{"name":"validateOnly","value":false},{"name":"value","value":""}],"input":[{"name":"IDToken4","value":"self10-user.10000001@forgerock.com"},{"name":"IDToken4validateOnly","value":false}],"_id":3},{"type":"BooleanAttributeInputCallback","output":[{"name":"name","value":"preferences/marketing"},{"name":"prompt","value":"Send me special offers and services"},{"name":"required","value":true},{"name":"policies","value":{}},{"name":"failedPolicies","value":[]},{"name":"validateOnly","value":false},{"name":"value","value":false}],"input":[{"name":"IDToken5","value":false},{"name":"IDToken5validateOnly","value":false}],"_id":4},{"type":"BooleanAttributeInputCallback","output":[{"name":"name","value":"preferences/updates"},{"name":"prompt","value":"Send me news and updates"},{"name":"required","value":true},{"name":"policies","value":{}},{"name":"failedPolicies","value":[]},{"name":"validateOnly","value":false},{"name":"value","value":false}],"input":[{"name":"IDToken6","value":false},{"name":"IDToken6validateOnly","value":false}],"_id":5},{"type":"ValidatedCreatePasswordCallback","output":[{"name":"echoOn","value":false},{"name":"policies","value":{"policyRequirements":["VALID_TYPE"],"fallbackPolicies":null,"name":"password","policies":[{"policyRequirements":["VALID_TYPE"],"policyId":"valid-type","params":{"types":["string"]}}],"conditionalPolicies":null}},{"name":"failedPolicies","value":[]},{"name":"validateOnly","value":false},{"name":"prompt","value":"Password"}],"input":[{"name":"IDToken7","value":"Pa_ssw0rd"},{"name":"IDToken7validateOnly","value":false}],"_id":6},{"type":"KbaCreateCallback","output":[{"name":"prompt","value":"Select a security question"},{"name":"predefinedQuestions","value":["What's your favorite color?"]},{"name":"allowUserDefinedQuestions","value":true}],"input":[{"name":"IDToken8question","value":"What's your favorite color?"},{"name":"IDToken8answer","value":"red"}],"_id":7},{"type":"TermsAndConditionsCallback","output":[{"name":"version","value":"0.0"},{"name":"terms","value":"Lorem ipsum dolor sit amet, consectetur adipiscing elit, sed do eiusmod tempor incididunt ut labore et dolore magna aliqua. Ut enim ad minim veniam, quis nostrud exercitation ullamco laboris nisi ut aliquip ex ea commodo consequat. Duis aute irure dolor in reprehenderit in voluptate velit esse cillum dolore eu fugiat nulla pariatur. Excepteur sint occaecat cupidatat non proident, sunt in culpa qui officia deserunt mollit anim id est laborum."},{"name":"createDate","value":"2019-10-28T04:20:11.320Z"}],"input":[{"name":"IDToken9","value":true}],"_id":8}],"header":"Sign Up","description":"Signing up is fast and easy.
Already have an account? Sign In"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-perf-haproxy.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-perf-haproxy.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1758223021356))), password -> Pa_ssw0rd, fake-ip -> 192.87.19.138, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@66a8ea1b),KO,List(GroupBlock(List(selfService: register and view profile),1758223020809,539,KO)),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$826/0x000079613469a3d0@1945412c,io.netty.channel.epoll.EpollEventLoop@14f5da2c)
=========================
HTTP request:
POST https://openam-perf-haproxy.forgeblocks.com/am/json/realms/root/realms/alpha/authenticate?authIndexType=service&authIndexValue=Registration
headers:
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
accept: application/json
content-type: application/json
cookie: amlbcookie=01
host: openam-perf-haproxy.forgeblocks.com
[...]
> selfService: login existing user and patch its telNum... (OK=4541 KO=1 )
> restLoginInitiate (OK=29908 KO=1 )
> selfService: login existing user and patch its telNum... (OK=4542 KO=0 )
> restLoginUsernamePasswordCallback (OK=29906 KO=2 )
> Skip 2FA (OK=21313 KO=0 )
> User Authorize PKCE stage (OK=31342 KO=1 )
> User AccessToken PKCE stage (OK=31340 KO=1 )
> RootUserinfo (OK=85094 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4541 KO=1 )
> selfService: login existing user and patch its telNum... (OK=4542 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4542 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4542 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4541 KO=1 )
> selfService: login existing user and patch its telNum... (OK=9081 KO=0 )
> tokenUserinfo (OK=7150 KO=0 )
> selfService: login existing user and patch its telNum... (OK=9081 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4542 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4542 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4542 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4542 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4540 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4540 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4540 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4540 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4539 KO=1 )
> selfService: login existing user and patch its telNum... (OK=4539 KO=1 )
> selfService: login existing user and patch its telNum... (OK=4540 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4540 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4540 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4540 KO=0 )
> selfService: login existing user and patch its telNum... (OK=4540 KO=1 )
> selfService: login existing user and patch its telNum... (OK=1831 KO=0 )
> selfService: login existing user and patch its telNum... (OK=1831 KO=0 )
> selfService: login existing user and patch its telNum... (OK=2516 KO=0 )
> selfService: login existing user and patch its telNum... (OK=2516 KO=0 )
> selfService: login existing user and patch its telNum... (OK=2516 KO=0 )
> selfService: login existing user and patch its telNum... (OK=2516 KO=0 )
> selfService: login existing user and patch its telNum... (OK=2516 KO=0 )
> getIdToken (OK=4286 KO=0 )
> getIdToken Redirect 1 (OK=4286 KO=0 )
> createOrganization (OK=4286 KO=0 )
> queryOrganization (OK=4286 KO=0 )
> deleteOrganization (OK=4286 KO=0 )
> selfService: register and view profile / registration... (OK=1152 KO=0 )
> selfService: register and view profile / registration... (OK=0 KO=1152 )
> selfService: register and view profile / UI-Post-Logi... (OK=1152 KO=0 )
> selfService: register and view profile / UI-Post-Logi... (OK=1152 KO=0 )
> selfService: register and view profile / UI AM idFro... (OK=0 KO=1152 )
> selfService: register and view profile / UI AM valid... (OK=1152 KO=0 )
> selfService: register and view profile / UI-Post-Logi... (OK=1152 KO=0 )
> selfService: register and view profile / UI-Post-Logi... (OK=1152 KO=0 )
> selfService: register and view profile / UI Enduser ... (OK=1152 KO=0 )
> selfService: register and view profile / UI Enduser ... (OK=1152 KO=0 )
> selfService: register and view profile / UI IDM oidc... (OK=0 KO=1152 )
> UI-Post-Login - stage 1 (OK=1440 KO=0 )
> UI-Post-Login - stage 1 Redirect 1 (OK=1440 KO=0 )
> UI AM idFromSession (OK=1440 KO=0 )
> UI AM validateGoto (OK=1440 KO=0 )
> UI-Post-Login - stage 2 (OK=1440 KO=0 )
> UI-Post-Login - stage 2 Redirect 1 (OK=1440 KO=0 )
> UI Enduser appAuthHelper1 (OK=1440 KO=0 )
> UI Enduser appAuthHelper2 (OK=1440 KO=0 )
> UI-Post-Login - stage 3 (OK=1439 KO=0 )
> UI-Post-Login - stage 3 Redirect 1 (OK=1439 KO=0 )
> UI Enduser sessioncheck (OK=1439 KO=0 )
> UI IDM uiconfig (OK=1439 KO=0 )
> UI IDM version (OK=1439 KO=0 )
> UI IDM privilege (OK=1439 KO=0 )
> UI IDM features (OK=1439 KO=0 )
> UI IDM login (OK=1439 KO=0 )
> UI IDM managed user schema (OK=1438 KO=0 )
> UI IDM dashboard (OK=1438 KO=0 )
> UI IDM user (OK=1438 KO=0 )
> UI IDM oidcToken (OK=1438 KO=0 )
> userinfo (OK=1438 KO=0 )
> UI-Logout (OK=1437 KO=0 )
> UI-Logout Redirect 1 (OK=1437 KO=0 )
> UI AM revoke1 (OK=1437 KO=0 )
> UI AM endsession (OK=1437 KO=0 )
> UI AM revoke2 (OK=1437 KO=0 )
---- Errors --------------------------------------------------------------------
> status.find.in([200, 209], 304), found 401 1153 (33.20%)
> headerRegex((location,#id_token=(.*))).find.exists, found noth 1153 (33.20%)
ing
> jsonPath($.tokenId).find.exists, found nothing 1152 (33.17%)
> RootUserinfo: No attribute named 'access_token' is defined 5 ( 0.14%)
> i.n.c.ConnectTimeoutException: connection timed out after 1000 3 ( 0.09%)
0 ms: openam-perf-haproxy.forgeblocks.com/34.120.144.60:443
> status.find.is(200), but actually found 502 3 ( 0.09%)
> status.find.in([200, 209], 304), found 502 2 ( 0.06%)
> status.find.is(302), but actually found 502 1 ( 0.03%)
> tokenUserinfo: No attribute named 'access_token' is defined 1 ( 0.03%)
---- idc.benchmark.CH2023 ------------------------------------------------------
[#########################################################################-] 99%
waiting: 286 / active: 30 / done: 35573
================================================================================
2025-09-18 21:16:50,644 INFO Gatling simulation run ended successfully
2025-09-18 21:16:50,644 DEBUG No archive name provided. No archive will be created
________________________________________________________________________________
[2025-09-18 20:17:11] benchmark-10 show_stderr : Display stderr order
________________________________________________________________________________
2025-09-18 20:17:11,766 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/01_benchmark-10/stderr.txt is small (0 lines), printing all lines:
2025-09-18 20:17:11,766 INFO ----- output -----
________________________________________________________________________________
[2025-09-18 20:17:11] benchmark-10 check_rc_and_logs : Check order's outputs
________________________________________________________________________________
CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15])
PASS: no error found in rc log
PASS: no error found in stdout log
PASS: no error found in stderr log
________________________________________________________________________________
[2025-09-18 20:17:12] benchmark-10 print_overseer_logs : Print overseer logs from the K8s pod
________________________________________________________________________________
Task result is INIT no need to display logs.
________________________________________________________________________________
[2025-09-18 20:17:12] benchmark-10 post : Post method
________________________________________________________________________________
Setting result to PASS
Task has been successfully stopped