--Task-- name: benchmark-550 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-500'] preceding_task: benchmark-500 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html', 'testCase': 'idc.benchmark.Mix2024', 'testMode': 'open', 'throughput': 550} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock 2025-06-14 03:07:17,016 INFO 2025-06-14 03:07:17,016 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-06-14 03:07:17,016 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-06-14 03:07:17,298 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-06-14 03:07:17,298 INFO [loop_until]: OK (rc = 0) 2025-06-14 03:07:17,298 DEBUG --- stdout --- 2025-06-14 03:07:17,298 DEBUG overseer-0-898c87646-bjhnj 2025-06-14 03:07:17,298 DEBUG --- stderr --- 2025-06-14 03:07:17,298 DEBUG 2025-06-14 03:07:17,298 INFO Lodestar assumed that full stack is deployed so will set all products host URLs in Gatling system properties 2025-06-14 03:07:17 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2025-06-14 03:07:17 - INFO: timeout was calculated based on simulation duration parameter 2025-06-14 03:07:17 - INFO: Interval for this Task has changed to 2m (120 seconds) 2025-06-14 03:07:17 - 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-550/stdout.txt Pod log : /results/orders/benchmark-550 ________________________________________________________________________________ [2025-06-14 03:07:17] benchmark-550 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.Mix2024@tof-10" --results-folder="/results/gatling/benchmark-550" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-tof-10.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-tof-10.forgeblocks.com -Dlogin_cookie=7573b68ed0f3690 -Dnum_users=10000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=550 -Duser_password=**** ________________________________________________________________________________ [2025-06-14 03:07:17] benchmark-550 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/07_benchmark-550/order.json { "name": "benchmark-550", "command": " /lodestar/lodestar.py perf run-gatling --mvn-quiet --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idc.benchmark.Mix2024@tof-10\" --results-folder=\"/results/gatling/benchmark-550\" --no-reports --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx4G\" --simulation-system-properties=\"-Dam_host=openam-tof-10.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-tof-10.forgeblocks.com -Dlogin_cookie=7573b68ed0f3690 -Dnum_users=10000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=550 -Duser_password=Pa_ssw0rd\"", "out-dir": "/results/orders/benchmark-550" } ________________________________________________________________________________ [2025-06-14 03:07:17] benchmark-550 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-06-14 03:07:17,306 INFO 2025-06-14 03:07:17,306 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-06-14 03:07:17,306 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2025-06-14 03:07:17,523 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-06-14 03:07:17,523 INFO [loop_until]: OK (rc = 0) 2025-06-14 03:07:17,523 DEBUG --- stdout --- 2025-06-14 03:07:17,523 DEBUG WAITING 2025-06-14 03:07:17,523 DEBUG --- stderr --- 2025-06-14 03:07:17,523 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2025-06-14 03:07:17] benchmark-550 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2025-06-14 03:07:17,523 INFO 2025-06-14 03:07:17,523 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-06-14 03:07:17,523 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-06-14 03:07:17,804 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-06-14 03:07:17,804 INFO [loop_until]: OK (rc = 0) 2025-06-14 03:07:17,804 DEBUG --- stdout --- 2025-06-14 03:07:17,804 DEBUG overseer-0-898c87646-bjhnj 2025-06-14 03:07:17,804 DEBUG --- stderr --- 2025-06-14 03:07:17,804 DEBUG 2025-06-14 03:07:17,805 INFO 2025-06-14 03:07:17,805 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/07_benchmark-550/order.json overseer-0-898c87646-bjhnj:/results/orders/order.json 2025-06-14 03:07:17,805 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-06-14 03:07:19,206 INFO [loop_until]: OK (rc = 0) 2025-06-14 03:07:19,206 DEBUG --- stdout --- 2025-06-14 03:07:19,206 DEBUG 2025-06-14 03:07:19,206 DEBUG --- stderr --- 2025-06-14 03:07:19,206 DEBUG Order has been successfully forwarded to overseer 2025-06-14 03:07:19 - INFO: Check if Gatling simulation started successfully 2025-06-14 03:07:19,206 INFO 2025-06-14 03:07:19,206 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj -- head --lines 5000 /results/orders/benchmark-550/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2025-06-14 03:07:19,206 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2025-06-14 03:07:19,951 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2025-06-14 03:07:25,707 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2025-06-14 03:07:31,493 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2025-06-14 03:07:37,242 INFO [loop_until]: Function succeeded after 18s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2025-06-14 03:07:43,004 INFO [loop_until]: Function succeeded after 23s (rc=0) - expected pattern found 2025-06-14 03:07:43,005 INFO [loop_until]: OK (rc = 0) 2025-06-14 03:07:43,005 DEBUG --- stdout --- 2025-06-14 03:07:43,005 DEBUG 2025-06-14 04:07:21,491 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpg6d0ttqa 2025-06-14 04:07:21,495 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-550" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-tof-10.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-tof-10.forgeblocks.com -Dlogin_cookie=7573b68ed0f3690 -Dnum_users=10000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=550 -Duser_password=**** 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-06-14 03:07:43,005 DEBUG --- stderr --- 2025-06-14 03:07:43,005 DEBUG ________________________________________________________________________________ [2025-06-14 03:07:43] benchmark-550 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m. Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 02s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 03s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 04s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 05s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 06s, retry Order is running since 46m 06s, retry Order is running since 48m 07s, retry Order is running since 50m 07s, retry Order is running since 52m 07s, retry Order is running since 54m 08s, 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) ________________________________________________________________________________ [2025-06-14 04:07:51] benchmark-550 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2025-06-14 04:07:51,864 INFO 2025-06-14 04:07:51,864 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-06-14 04:07:51,864 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-06-14 04:07:52,162 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-06-14 04:07:52,162 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:52,162 DEBUG --- stdout --- 2025-06-14 04:07:52,162 DEBUG overseer-0-898c87646-bjhnj 2025-06-14 04:07:52,162 DEBUG --- stderr --- 2025-06-14 04:07:52,162 DEBUG 2025-06-14 04:07:52,163 INFO 2025-06-14 04:07:52,163 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj -- rm -f /results/orders/order.json 2025-06-14 04:07:52,163 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-06-14 04:07:52,945 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:52,945 DEBUG --- stdout --- 2025-06-14 04:07:52,945 DEBUG 2025-06-14 04:07:52,945 DEBUG --- stderr --- 2025-06-14 04:07:52,945 DEBUG Order ran during 1h ________________________________________________________________________________ [2025-06-14 04:07:52] benchmark-550 download_logs : Download individual order files ________________________________________________________________________________ 2025-06-14 04:07:52 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-550/order.json 2025-06-14 04:07:52,945 INFO 2025-06-14 04:07:52,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 2025-06-14 04:07:52,946 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-06-14 04:07:53,230 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-06-14 04:07:53,230 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:53,230 DEBUG --- stdout --- 2025-06-14 04:07:53,231 DEBUG overseer-0-898c87646-bjhnj 2025-06-14 04:07:53,231 DEBUG --- stderr --- 2025-06-14 04:07:53,231 DEBUG Checking if file exists on pod 'overseer-0-898c87646-bjhnj', wait max 3 minutes if not. 2025-06-14 04:07:53,231 INFO 2025-06-14 04:07:53,231 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj --stdin -- bash -c 'if [ -f "/results/orders/benchmark-550/rc.txt" ]; then echo "/results/orders/benchmark-550/rc.txt file found"; exit 0; else echo "/results/orders/benchmark-550/rc.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-550/rc.txt file found" 2025-06-14 04:07:53,231 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-06-14 04:07:54,036 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-06-14 04:07:54,036 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:54,036 DEBUG --- stdout --- 2025-06-14 04:07:54,037 DEBUG /results/orders/benchmark-550/rc.txt file found 2025-06-14 04:07:54,037 DEBUG --- stderr --- 2025-06-14 04:07:54,037 DEBUG 2025-06-14 04:07:54,037 INFO 2025-06-14 04:07:54,037 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj --stdin -- du -k /results/orders/benchmark-550/rc.txt | grep "^\d+ .+$" 2025-06-14 04:07:54,037 INFO [loop_until]: (max_time=240, interval=10, expected_rc=[0] 2025-06-14 04:07:54,881 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-06-14 04:07:54,881 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:54,881 DEBUG --- stdout --- 2025-06-14 04:07:54,881 DEBUG 4 /results/orders/benchmark-550/rc.txt 2025-06-14 04:07:54,881 DEBUG --- stderr --- 2025-06-14 04:07:54,881 DEBUG - Download file (size 4kB) source : /results/orders/benchmark-550/rc.txt @ overseer-0-898c87646-bjhnj destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-550/rc.txt 2025-06-14 04:07:54,882 INFO 2025-06-14 04:07:54,882 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-898c87646-bjhnj:/results/orders/benchmark-550/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-550/rc.txt 2025-06-14 04:07:54,882 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-06-14 04:07:55,616 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:55,616 DEBUG --- stdout --- 2025-06-14 04:07:55,616 DEBUG tar: Removing leading `/' from member names 2025-06-14 04:07:55,616 DEBUG --- stderr --- 2025-06-14 04:07:55,616 DEBUG 2025-06-14 04:07:55,617 INFO 2025-06-14 04:07:55,617 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-06-14 04:07:55,617 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-06-14 04:07:55,897 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-06-14 04:07:55,897 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:55,897 DEBUG --- stdout --- 2025-06-14 04:07:55,897 DEBUG overseer-0-898c87646-bjhnj 2025-06-14 04:07:55,897 DEBUG --- stderr --- 2025-06-14 04:07:55,897 DEBUG Checking if file exists on pod 'overseer-0-898c87646-bjhnj', wait max 3 minutes if not. 2025-06-14 04:07:55,898 INFO 2025-06-14 04:07:55,898 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj --stdin -- bash -c 'if [ -f "/results/orders/benchmark-550/stderr.txt" ]; then echo "/results/orders/benchmark-550/stderr.txt file found"; exit 0; else echo "/results/orders/benchmark-550/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-550/stderr.txt file found" 2025-06-14 04:07:55,898 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-06-14 04:07:56,697 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-06-14 04:07:56,697 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:56,697 DEBUG --- stdout --- 2025-06-14 04:07:56,697 DEBUG /results/orders/benchmark-550/stderr.txt file found 2025-06-14 04:07:56,697 DEBUG --- stderr --- 2025-06-14 04:07:56,697 DEBUG 2025-06-14 04:07:56,697 INFO 2025-06-14 04:07:56,697 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj --stdin -- du -k /results/orders/benchmark-550/stderr.txt | grep "^\d+ .+$" 2025-06-14 04:07:56,697 INFO [loop_until]: (max_time=240, interval=10, expected_rc=[0] 2025-06-14 04:07:57,498 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-06-14 04:07:57,499 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:57,499 DEBUG --- stdout --- 2025-06-14 04:07:57,499 DEBUG 0 /results/orders/benchmark-550/stderr.txt 2025-06-14 04:07:57,499 DEBUG --- stderr --- 2025-06-14 04:07:57,499 DEBUG - Download file (size 0B) source : /results/orders/benchmark-550/stderr.txt @ overseer-0-898c87646-bjhnj destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-550/stderr.txt 2025-06-14 04:07:57,499 INFO 2025-06-14 04:07:57,499 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-898c87646-bjhnj:/results/orders/benchmark-550/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-550/stderr.txt 2025-06-14 04:07:57,499 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-06-14 04:07:58,230 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:58,230 DEBUG --- stdout --- 2025-06-14 04:07:58,230 DEBUG tar: Removing leading `/' from member names 2025-06-14 04:07:58,230 DEBUG --- stderr --- 2025-06-14 04:07:58,230 DEBUG 2025-06-14 04:07:58,231 INFO 2025-06-14 04:07:58,231 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-06-14 04:07:58,231 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-06-14 04:07:58,509 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-06-14 04:07:58,509 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:58,509 DEBUG --- stdout --- 2025-06-14 04:07:58,510 DEBUG overseer-0-898c87646-bjhnj 2025-06-14 04:07:58,510 DEBUG --- stderr --- 2025-06-14 04:07:58,510 DEBUG Checking if file exists on pod 'overseer-0-898c87646-bjhnj', wait max 3 minutes if not. 2025-06-14 04:07:58,510 INFO 2025-06-14 04:07:58,510 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj --stdin -- bash -c 'if [ -f "/results/orders/benchmark-550/stdout.txt" ]; then echo "/results/orders/benchmark-550/stdout.txt file found"; exit 0; else echo "/results/orders/benchmark-550/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-550/stdout.txt file found" 2025-06-14 04:07:58,510 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-06-14 04:07:59,335 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-06-14 04:07:59,335 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:07:59,335 DEBUG --- stdout --- 2025-06-14 04:07:59,335 DEBUG /results/orders/benchmark-550/stdout.txt file found 2025-06-14 04:07:59,335 DEBUG --- stderr --- 2025-06-14 04:07:59,335 DEBUG 2025-06-14 04:07:59,335 INFO 2025-06-14 04:07:59,335 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj --stdin -- du -k /results/orders/benchmark-550/stdout.txt | grep "^\d+ .+$" 2025-06-14 04:07:59,335 INFO [loop_until]: (max_time=240, interval=10, expected_rc=[0] 2025-06-14 04:08:00,146 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-06-14 04:08:00,146 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:08:00,146 DEBUG --- stdout --- 2025-06-14 04:08:00,146 DEBUG 1165284 /results/orders/benchmark-550/stdout.txt 2025-06-14 04:08:00,146 DEBUG --- stderr --- 2025-06-14 04:08:00,146 DEBUG - Download file (size 1.165GB exceeds limit of 100MB, only first 1000 and last 1000 lines will be downloaded) source : /results/orders/benchmark-550/stdout.txt @ overseer-0-898c87646-bjhnj destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-550/stdout.txt 2025-06-14 04:08:00,147 INFO 2025-06-14 04:08:00,148 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj --stdin -- head -n 1000 /results/orders/benchmark-550/stdout.txt 2025-06-14 04:08:01,051 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-06-14 04:08:01,051 DEBUG --- stdout --- 2025-06-14 04:08:01,051 DEBUG 2025-06-14 04:07:21,491 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpg6d0ttqa 2025-06-14 04:07:21,495 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-550" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-tof-10.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-tof-10.forgeblocks.com -Dlogin_cookie=7573b68ed0f3690 -Dnum_users=10000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=550 -Duser_password=**** 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-06-14 03:07:44 GMT 5s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=1640 KO=0 ) > authAsPolicyAdmin (OK=1 KO=0 ) > userRestlogin (OK=62 KO=0 ) > restLoginInitiate (OK=147 KO=0 ) > EvalPolicy (OK=58 KO=0 ) > restLoginUsernamePasswordCallback (OK=141 KO=0 ) > Skip 2FA (OK=44 KO=0 ) > UI-Post-Login - stage 1 (OK=46 KO=0 ) > User Authorize PKCE stage (OK=154 KO=0 ) > UI-Post-Login - stage 1 Redirect 1 (OK=42 KO=0 ) > UI AM idFromSession (OK=41 KO=0 ) > User AccessToken PKCE stage (OK=150 KO=0 ) > UI AM validateGoto (OK=41 KO=0 ) > RootUserinfo (OK=55 KO=0 ) > tokenUserinfo (OK=44 KO=0 ) > loginTreeWorkflow_restLoginInitiate (OK=11 KO=0 ) > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=11 KO=0 ) > UI-Post-Login - stage 2 (OK=37 KO=0 ) > UI-Post-Login - stage 2 Redirect 1 (OK=37 KO=0 ) > UI Enduser appAuthHelper1 (OK=33 KO=0 ) > UI Enduser appAuthHelper2 (OK=33 KO=0 ) > getIdToken (OK=11 KO=0 ) > getIdToken Redirect 1 (OK=10 KO=0 ) > UI-Post-Login - stage 3 (OK=28 KO=0 ) > createOrganization (OK=9 KO=0 ) > UI-Post-Login - stage 3 Redirect 1 (OK=27 KO=0 ) > queryOrganization (OK=8 KO=0 ) > UI Enduser sessioncheck (OK=25 KO=0 ) > UI IDM uiconfig (OK=25 KO=0 ) > UI IDM version (OK=25 KO=0 ) > UI IDM features (OK=25 KO=0 ) > UI IDM login (OK=25 KO=0 ) > UI IDM privilege (OK=25 KO=0 ) > deleteOrganization (OK=8 KO=0 ) > UI IDM dashboard (OK=24 KO=0 ) > UI IDM user (OK=24 KO=0 ) > UI IDM managed user schema (OK=23 KO=0 ) > UI IDM oidcToken (OK=21 KO=0 ) > userinfo (OK=20 KO=0 ) > UI-Logout (OK=19 KO=0 ) > UI-Logout Redirect 1 (OK=19 KO=0 ) > UI AM revoke2 (OK=17 KO=0 ) > UI AM endsession (OK=17 KO=0 ) > UI AM revoke1 (OK=17 KO=0 ) ---- getToken ------------------------------------------------------------------ [--------------------------------------------------------------------------] 0% waiting: 0 / active: 1 / done: 0 ---- idc.benchmark.Mix2024 ----------------------------------------------------- [- ] 0% waiting: 1987009 / active: 91 / done: 138 ================================================================================ ================================================================================ 2025-06-14 03:07:49 GMT 10s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=7899 KO=0 ) > authAsPolicyAdmin (OK=1 KO=0 ) > userRestlogin (OK=282 KO=0 ) > restLoginInitiate (OK=584 KO=0 ) > EvalPolicy (OK=271 KO=0 ) > restLoginUsernamePasswordCallback (OK=577 KO=0 ) > Skip 2FA (OK=194 KO=0 ) > UI-Post-Login - stage 1 (OK=181 KO=0 ) > User Authorize PKCE stage (OK=702 KO=0 ) > UI-Post-Login - stage 1 Redirect 1 (OK=179 KO=0 ) > UI AM idFromSession (OK=173 KO=0 ) > User AccessToken PKCE stage (OK=686 KO=0 ) > UI AM validateGoto (OK=173 KO=0 ) > RootUserinfo (OK=331 KO=0 ) > tokenUserinfo (OK=183 KO=0 ) > loginTreeWorkflow_restLoginInitiate (OK=28 KO=0 ) > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=27 KO=0 ) > UI-Post-Login - stage 2 (OK=164 KO=0 ) > UI-Post-Login - stage 2 Redirect 1 (OK=163 KO=0 ) > UI Enduser appAuthHelper1 (OK=158 KO=0 ) > UI Enduser appAuthHelper2 (OK=158 KO=0 ) > getIdToken (OK=66 KO=0 ) > getIdToken Redirect 1 (OK=63 KO=0 ) > UI-Post-Login - stage 3 (OK=146 KO=0 ) > createOrganization (OK=63 KO=0 ) > UI-Post-Login - stage 3 Redirect 1 (OK=142 KO=0 ) > queryOrganization (OK=62 KO=0 ) > UI Enduser sessioncheck (OK=138 KO=0 ) > UI IDM uiconfig (OK=138 KO=0 ) > UI IDM version (OK=137 KO=0 2025-06-14 04:08:01,051 DEBUG --- stderr --- 2025-06-14 04:08:01,051 DEBUG 2025-06-14 04:08:01,051 INFO 2025-06-14 04:08:01,052 INFO 2025-06-14 04:08:01,052 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj --stdin -- tail -n 1000 /results/orders/benchmark-550/stdout.txt 2025-06-14 04:08:02,069 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-06-14 04:08:02,069 DEBUG --- stdout --- 2025-06-14 04:08:02,069 DEBUG [print_head_tail]: Print head (80 lines) and tail (80 lines) of input string (1000 lines): 2025-06-14 04:08:02,069 DEBUG ----- output ----- cookies: amlbcookie=01, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 7573b68ed0f3690=QmnhhrEFXZXNKCHgyxMPuvrj-W4.*AAJTSQACMDIAAlNLABxaaVlTVSt5RlZkY3BFbjVWSE9LMjR3dlJzdUE9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 05:07:39.696 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'restLoginInitiate' failed for user 1970674: j.i.IOException: Premature close 05:07:39.696 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: userRestlogin: KO j.i.IOException: Premature close ========================= Session: Session(idc.benchmark.Mix2024,1970667,HashMap(gatling.http.cache.baseUrl -> https://openam-tof-10.forgeblocks.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@6c6cc318, username -> user.8771173, tokenIdCookieName -> amlbcookie, policyEvalTokenId -> , gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@526c343a, password -> Pa_ssw0rd),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$821/0x00007b26e06983d0@6010a3ba,io.netty.channel.epoll.EpollEventLoop@650eab8) ========================= HTTP request: POST https://openam-tof-10.forgeblocks.com/am/json/realms/root/realms/alpha/authenticate headers: Content-Type: apppliation/json Accept-API-Version: resource=2.0, protocol=1.0 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 X-OpenAM-Username: user.8771173 X-OpenAM-Password: Pa_ssw0rd accept: */* host: openam-tof-10.forgeblocks.com content-length: 0 ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 05:07:39.697 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request EvalPolicy: No attribute named 'userTokenId' is defined 05:07:39.697 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'restLoginInitiate' failed for user 1970652: j.i.IOException: Premature close 05:07:39.697 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM login' failed for user 1969888: j.i.IOException: Premature close 05:07:39.696 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: UI IDM privilege: KO j.i.IOException: Premature close ========================= Session: Session(idc.benchmark.Mix2024,1969917,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlNzI2MWY5Yy02NTZmLTRmMDktYTdiNi04NjFkYjdiNWVmMDQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMWM0YWQwZmEtOTlmNS00MjZiLTgzNDQtNmYxNzg5NTU2ZTUzLTE5ODQwNjY4MSIsInN1Ym5hbWUiOiJlNzI2MWY5Yy02NTZmLTRmMDktYTdiNi04NjFkYjdiNWVmMDQiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoiLTNZUjNCR0RPRWs0a05aLVZTcUN1bXpram1ZIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU4LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsib3BlbmlkIiwiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OCwiaWF0IjoxNzQ5ODc0MDU4LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiJZTkYtUFBrMERON0tsUFNBalRvZWcwdFVNYkEifQ.o5oKjhZc-QIv36x31i5X2gx5tDbL3YNuaHdbh98wfm4, username -> user.9041376, tokenIdCookieName -> amlbcookie, policyEvalTokenId -> , access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlNzI2MWY5Yy02NTZmLTRmMDktYTdiNi04NjFkYjdiNWVmMDQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMWM0YWQwZmEtOTlmNS00MjZiLTgzNDQtNmYxNzg5NTU2ZTUzLTE5ODQxMjM3MCIsInN1Ym5hbWUiOiJlNzI2MWY5Yy02NTZmLTRmMDktYTdiNi04NjFkYjdiNWVmMDQiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoickZIWnRGWEtXSjhsaDZmUndReEJRckFFNFZ3Iiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU5LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OSwiaWF0IjoxNzQ5ODc0MDU5LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiJMOXNnU0ZuVGV6empQUU43WG1ka1AyQlEtbWcifQ.r3BksPSkBMGzWZg7l3a42xIC5h038O6IHFCQ0Yq5CYc, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@612343dd, gatling.http.referer -> https://openam-tof-10.forgeblocks.com/am/, authcode -> 9P7w6-p83ePNn_AjTqN9Y871gc4, callbacks -> {"tokenId":"9tW0iNbTEYNCEvP_ng38VKI8KCE.*AAJTSQACMDIAAlNLABxFVnRYb0hxNkRGYzg0SVp4MFhYZWFsbi91OGs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-tof-10.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1749874058503), CookieKey(7573b68ed0f3690,openam-tof-10.forgeblocks.com,/) -> StoredCookie(7573b68ed0f3690=9tW0iNbTEYNCEvP_ng38VKI8KCE.*AAJTSQACMDIAAlNLABxFVnRYb0hxNkRGYzg0SVp4MFhYZWFsbi91OGs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1749874058503))), user_uuid -> e7261f9c-656f-4f09-a7b6-861db7b5ef04, password -> Pa_ssw0rd, challenge -> Dx-dFdm3tDB2g9DvPbUiTSAt9KjMi7oqZdLO2v6-_yM, gatling.http.cache.baseUrl -> https://openam-tof-10.forgeblocks.com:443, fake-ip -> 207.65.70.9, verifier -> NjIwOTM5Nzk3MjM4Nzc2NDU4Mw, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@fb5c5e1, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@79fbee72, tokenId -> 9tW0iNbTEYNCEvP_ng38VKI8KCE.*AAJTSQACMDIAAlNLABxFVnRYb0hxNkRGYzg0SVp4MFhYZWFsbi91OGs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, 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$821/0x00007b26e06983d0@6010a3ba,io.netty.channel.epoll.EpollEventLoop@1e1d3956) ========================= HTTP request: POST https://openam-tof-10.forgeblocks.com/openidm/privilege?_action=listPrivileges 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 referer: https://openam-tof-10.forgeblocks.com/am/ Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJlNzI2MWY5Yy02NTZmLTRmMDktYTdiNi04NjFkYjdiNWVmMDQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMWM0YWQwZmEtOTlmNS00MjZiLTgzNDQtNmYxNzg5NTU2ZTUzLTE5ODQxMjM3MCIsInN1Ym5hbWUiOiJlNzI2MWY5Yy02NTZmLTRmMDktYTdiNi04NjFkYjdiNWVmMDQiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoickZIWnRGWEtXSjhsaDZmUndReEJRckFFNFZ3Iiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU5LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OSwiaWF0IjoxNzQ5ODc0MDU5LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiJMOXNnU0ZuVGV6empQUU43WG1ka1AyQlEtbWcifQ.r3BksPSkBMGzWZg7l3a42xIC5h038O6IHFCQ0Yq5CYc Accept-Api-Version: accept: */* cookie: amlbcookie=01; 7573b68ed0f3690=9tW0iNbTEYNCEvP_ng38VKI8KCE.*AAJTSQACMDIAAlNLABxFVnRYb0hxNkRGYzg0SVp4MFhYZWFsbi91OGs9AAR0eXBlAANDVFMAAlMxAAIwMQ..* origin: https://openam-tof-10.forgeblocks.com host: openam-tof-10.forgeblocks.com content-length: 0 cookies: amlbcookie=01, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 7573b68ed0f3690=9tW0iNbTEYNCEvP_ng38VKI8KCE.*AAJTSQACMDIAAlNLABxFVnRYb0hxNkRGYzg0SVp4MFhYZWFsbi91OGs9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 05:07:39.697 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: UI IDM login: KO j.i.IOException: Premature close ========================= Session: Session(idc.benchmark.Mix2024,1969888,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkZTVhOTRjYi01NDI5LTRkMTAtOWM1NC1kNmY2NjI1ZmMwMjUiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiOTVkNWVkZWItYmRlZC00NGY3LWFlMjAtNGY2OWI1OTk2MmJlLTE5OTAxNDUxNiIsInN1Ym5hbWUiOiJkZTVhOTRjYi01NDI5LTRkMTAtOWM1NC1kNmY2NjI1ZmMwMjUiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoiT1NodnlPak5xa3Y0eXVHdTh1M1ltelZKNllRIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU4LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsib3BlbmlkIiwiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OCwiaWF0IjoxNzQ5ODc0MDU4LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiI0WEZxbHlicFJ6Y3V3NVk0QmhBUnBHYzhrNlkifQ.YNyaCfnOwo3CGcBz_prZsV9d0Ls4SnMMSPhQb2WCGaA, username -> user.6182402, tokenIdCookieName -> amlbcookie, policyEvalTokenId -> , access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkZTVhOTRjYi01NDI5LTRkMTAtOWM1NC1kNmY2NjI1ZmMwMjUiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMWM0YWQwZmEtOTlmNS00MjZiLTgzNDQtNmYxNzg5NTU2ZTUzLTE5ODQxMTUwOCIsInN1Ym5hbWUiOiJkZTVhOTRjYi01NDI5LTRkMTAtOWM1NC1kNmY2NjI1ZmMwMjUiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoiNWVJRG0tcThjTEVjX1hUU01zalhPTmZFMXdVIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU5LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OSwiaWF0IjoxNzQ5ODc0MDU5LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiJLcm8xLTBTVi1tdDNYa3ZPem5VeFJMck8wR1EifQ.mQaPHDYdhncagF0W6DjhMnjCABfCPeLEx9dqpnV9xGI, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@7357da8d, gatling.http.referer -> https://openam-tof-10.forgeblocks.com/am/, authcode -> lgn_cUjYgVxo8moboFVBpOl2GOc, callbacks -> {"tokenId":"8sRngSte9O4tegW4pBUep3dCwxw.*AAJTSQACMDIAAlNLABxYbC9TeGJTSjBhall3SDZINTh5dmhBQ01yMW89AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-tof-10.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1749874058439), CookieKey(7573b68ed0f3690,openam-tof-10.forgeblocks.com,/) -> StoredCookie(7573b68ed0f3690=8sRngSte9O4tegW4pBUep3dCwxw.*AAJTSQACMDIAAlNLABxYbC9TeGJTSjBhall3SDZINTh5dmhBQ01yMW89AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1749874058439))), user_uuid -> de5a94cb-5429-4d10-9c54-d6f6625fc025, password -> Pa_ssw0rd, challenge -> znLh1-vC1IRf_DXdxKOohZorJ_SxE_ujI55wx-74v5w, gatling.http.cache.baseUrl -> https://openam-tof-10.forgeblocks.com:443, fake-ip -> 22.75.121.187, verifier -> MjAxNTk3NTkxMzE5NTgyNTA2NQ, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@58b765eb, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@3d4cfd8e, tokenId -> 8sRngSte9O4tegW4pBUep3dCwxw.*AAJTSQACMDIAAlNLABxYbC9TeGJTSjBhall3SDZINTh5dmhBQ01yMW89AAR0eXBlAANDVFMAAlMxAAIwMQ..*, 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),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$821/0x00007b26e06983d0@6010a3ba,io.netty.channel.epoll.EpollEventLoop@636e8cc) ========================= HTTP request: POST https://openam-tof-10.forgeblocks.com/openidm/authentication?_action=login 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 referer: https://openam-tof-10.forgeblocks.com/am/ Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiJkZTVhOTRjYi01NDI5LTRkMTAtOWM1NC1kNmY2NjI1ZmMwMjUiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMWM0YWQwZmEtOTlmNS00MjZiLTgzNDQtNmYxNzg5NTU2ZTUzLTE5ODQxMTUwOCIsInN1Ym5hbWUiOiJkZTVhOTRjYi01NDI5LTRkMTAtOWM1NC1kNmY2NjI1ZmMwMjUiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoiNWVJRG0tcThjTEVjX1hUU01zalhPTmZFMXdVIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU5LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OSwiaWF0IjoxNzQ5ODc0MDU5LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiJLcm8xLTBTVi1tdDNYa3ZPem5VeFJMck8wR1EifQ.mQaPHDYdhncagF0W6DjhMnjCABfCPeLEx9dqpnV9xGI Accept-Api-Version: accept: */* cookie: amlbcookie=01; 7573b68ed0f3690=8sRngSte9O4tegW4pBUep3dCwxw.*AAJTSQACMDIAAlNLABxYbC9TeGJTSjBhall3SDZINTh5dmhBQ01yMW89AAR0eXBlAANDVFMAAlMxAAIwMQ..* origin: https://openam-tof-10.forgeblocks.com host: openam-tof-10.forgeblocks.com content-length: 0 cookies: amlbcookie=01, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None [...] <<<<<<<<<<<<<<<<<<<<<<<<< 05:07:39.701 [ERROR] i.g.c.a.b.SessionHookBuilder$$anon$1 - 'hook-160' crashed with 'j.u.NoSuchElementException: No attribute named 'callbacks' is defined', forwarding to the next one 05:07:39.701 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request EvalPolicy: No attribute named 'userTokenId' is defined 05:07:39.702 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'userRestlogin' failed for user 1970695: j.i.IOException: Premature close 05:07:39.702 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: userRestlogin: KO j.i.IOException: Premature close ========================= Session: Session(idc.benchmark.Mix2024,1970695,HashMap(gatling.http.cache.baseUrl -> https://openam-tof-10.forgeblocks.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@655d0e97, username -> user.1666624, tokenIdCookieName -> amlbcookie, policyEvalTokenId -> , gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@30418d9b, password -> Pa_ssw0rd),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$821/0x00007b26e06983d0@6010a3ba,io.netty.channel.epoll.EpollEventLoop@1eb6749b) ========================= HTTP request: POST https://openam-tof-10.forgeblocks.com/am/json/realms/root/realms/alpha/authenticate headers: Content-Type: apppliation/json Accept-API-Version: resource=2.0, protocol=1.0 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 X-OpenAM-Username: user.1666624 X-OpenAM-Password: Pa_ssw0rd accept: */* host: openam-tof-10.forgeblocks.com content-length: 0 ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 05:07:39.702 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request EvalPolicy: No attribute named 'userTokenId' is defined 05:07:39.702 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM privilege' failed for user 1969920: j.i.IOException: Premature close 05:07:39.702 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: UI IDM privilege: KO j.i.IOException: Premature close ========================= Session: Session(idc.benchmark.Mix2024,1969920,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZmY3MTA1ZDYtMmNiMS00ZTE3LWIyYmItZDkyY2YyMDkzMDU4LTE5Njk0MzE0NiIsInN1Ym5hbWUiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoiM3BDdmJJai1kV2lPZ3pCUkVrV1NUMDM5THJFIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU4LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsib3BlbmlkIiwiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OCwiaWF0IjoxNzQ5ODc0MDU4LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiJDaXpCRnAxZ2QzMjNMaUE0cnVtc0JrRTdVQ2MifQ.phAJ0dffelVlzqCAI2APbSRlJ2cYfeU6GW-v0v8RBd0, username -> user.6138441, tokenIdCookieName -> amlbcookie, policyEvalTokenId -> , access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZmY3MTA1ZDYtMmNiMS00ZTE3LWIyYmItZDkyY2YyMDkzMDU4LTE5Njk0ODU0NCIsInN1Ym5hbWUiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoidkZ0LTF5aWhzd19FOWR2TGpUWlM1WlpLXzJNIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU5LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OSwiaWF0IjoxNzQ5ODc0MDU5LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiI1SVduY0Y1QURFbDNRRnpwcFpNbVZPZXFXdjgifQ.SDkwqxAl4el_YlL2TC501G5NrAVGYalQe_2hcL8jtPc, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@2862f28c, gatling.http.referer -> https://openam-tof-10.forgeblocks.com/am/, authcode -> ZEaTv8bmhlc-cZCPY75zOrYa16U, callbacks -> {"tokenId":"_UURdApPlu4ooOEW9boWsimbim0.*AAJTSQACMDIAAlNLABxzbEpPNHdCM05SejNrS2gzRzlEUmZZWXppekU9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-tof-10.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1749874058511), CookieKey(7573b68ed0f3690,openam-tof-10.forgeblocks.com,/) -> StoredCookie(7573b68ed0f3690=_UURdApPlu4ooOEW9boWsimbim0.*AAJTSQACMDIAAlNLABxzbEpPNHdCM05SejNrS2gzRzlEUmZZWXppekU9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1749874058511))), user_uuid -> 233603b5-78bd-4308-855a-2651b75b70fd, password -> Pa_ssw0rd, challenge -> BOkUwognfOmwqAKqVL964C4dFxoTVHfLVZijaKjCuSs, gatling.http.cache.baseUrl -> https://openam-tof-10.forgeblocks.com:443, fake-ip -> 122.68.213.174, verifier -> LTEwNDMxNDcxOTkyNTc1MzI1OTc, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@3b9c151b, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@16370975, tokenId -> _UURdApPlu4ooOEW9boWsimbim0.*AAJTSQACMDIAAlNLABxzbEpPNHdCM05SejNrS2gzRzlEUmZZWXppekU9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.157 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$821/0x00007b26e06983d0@6010a3ba,io.netty.channel.epoll.EpollEventLoop@636e8cc) ========================= HTTP request: POST https://openam-tof-10.forgeblocks.com/openidm/privilege?_action=listPrivileges 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 referer: https://openam-tof-10.forgeblocks.com/am/ Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZmY3MTA1ZDYtMmNiMS00ZTE3LWIyYmItZDkyY2YyMDkzMDU4LTE5Njk0ODU0NCIsInN1Ym5hbWUiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoidkZ0LTF5aWhzd19FOWR2TGpUWlM1WlpLXzJNIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU5LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OSwiaWF0IjoxNzQ5ODc0MDU5LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiI1SVduY0Y1QURFbDNRRnpwcFpNbVZPZXFXdjgifQ.SDkwqxAl4el_YlL2TC501G5NrAVGYalQe_2hcL8jtPc Accept-Api-Version: accept: */* cookie: amlbcookie=01; 7573b68ed0f3690=_UURdApPlu4ooOEW9boWsimbim0.*AAJTSQACMDIAAlNLABxzbEpPNHdCM05SejNrS2gzRzlEUmZZWXppekU9AAR0eXBlAANDVFMAAlMxAAIwMQ..* origin: https://openam-tof-10.forgeblocks.com host: openam-tof-10.forgeblocks.com content-length: 0 cookies: amlbcookie=01, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 7573b68ed0f3690=_UURdApPlu4ooOEW9boWsimbim0.*AAJTSQACMDIAAlNLABxzbEpPNHdCM05SejNrS2gzRzlEUmZZWXppekU9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 05:07:39.703 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'userRestlogin' failed for user 1970687: j.i.IOException: Premature close 05:07:39.703 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: userRestlogin: KO j.i.IOException: Premature close ========================= Session: Session(idc.benchmark.Mix2024,1970687,HashMap(gatling.http.cache.baseUrl -> https://openam-tof-10.forgeblocks.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7ee890fe, username -> user.3575023, tokenIdCookieName -> amlbcookie, policyEvalTokenId -> , gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@278f4642, password -> Pa_ssw0rd),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$821/0x00007b26e06983d0@6010a3ba,io.netty.channel.epoll.EpollEventLoop@4e28bdd1) ========================= HTTP request: POST https://openam-tof-10.forgeblocks.com/am/json/realms/root/realms/alpha/authenticate headers: Content-Type: apppliation/json Accept-API-Version: resource=2.0, protocol=1.0 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 X-OpenAM-Username: user.3575023 X-OpenAM-Password: Pa_ssw0rd accept: */* host: openam-tof-10.forgeblocks.com content-length: 0 ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 05:07:39.703 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request EvalPolicy: No attribute named 'userTokenId' is defined 2025-06-14 05:07:40,109 INFO Gatling simulation run ended successfully 2025-06-14 05:07:40,109 DEBUG No archive name provided. No archive will be created 2025-06-14 04:08:02,069 DEBUG --- stderr --- 2025-06-14 04:08:02,069 DEBUG 2025-06-14 04:08:02,069 INFO ________________________________________________________________________________ [2025-06-14 04:08:02] benchmark-550 show_cmd : Display order command ________________________________________________________________________________ 2025-06-14 04:08:02,070 INFO 2025-06-14 04:08:02,070 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-06-14 04:08:02,070 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-06-14 04:08:02,347 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-06-14 04:08:02,347 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:08:02,347 DEBUG --- stdout --- 2025-06-14 04:08:02,347 DEBUG overseer-0-898c87646-bjhnj 2025-06-14 04:08:02,347 DEBUG --- stderr --- 2025-06-14 04:08:02,347 DEBUG Checking if file exists on pod 'overseer-0-898c87646-bjhnj', wait max 3 minutes if not. 2025-06-14 04:08:02,348 INFO 2025-06-14 04:08:02,348 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj --stdin -- bash -c 'if [ -f "/results/orders/benchmark-550/cmd.txt" ]; then echo "/results/orders/benchmark-550/cmd.txt file found"; exit 0; else echo "/results/orders/benchmark-550/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/benchmark-550/cmd.txt file found" 2025-06-14 04:08:02,348 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-06-14 04:08:03,150 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-06-14 04:08:03,150 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:08:03,150 DEBUG --- stdout --- 2025-06-14 04:08:03,150 DEBUG /results/orders/benchmark-550/cmd.txt file found 2025-06-14 04:08:03,150 DEBUG --- stderr --- 2025-06-14 04:08:03,150 DEBUG 2025-06-14 04:08:03,150 INFO 2025-06-14 04:08:03,150 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-898c87646-bjhnj --stdin -- du -k /results/orders/benchmark-550/cmd.txt | grep "^\d+ .+$" 2025-06-14 04:08:03,150 INFO [loop_until]: (max_time=240, interval=10, expected_rc=[0] 2025-06-14 04:08:03,957 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-06-14 04:08:03,957 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:08:03,957 DEBUG --- stdout --- 2025-06-14 04:08:03,958 DEBUG 4 /results/orders/benchmark-550/cmd.txt 2025-06-14 04:08:03,958 DEBUG --- stderr --- 2025-06-14 04:08:03,958 DEBUG - Download file (size 4kB) source : /results/orders/benchmark-550/cmd.txt @ overseer-0-898c87646-bjhnj destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-550/cmd.txt 2025-06-14 04:08:03,958 INFO 2025-06-14 04:08:03,958 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-898c87646-bjhnj:/results/orders/benchmark-550/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-550/cmd.txt 2025-06-14 04:08:03,958 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-06-14 04:08:04,691 INFO [loop_until]: OK (rc = 0) 2025-06-14 04:08:04,691 DEBUG --- stdout --- 2025-06-14 04:08:04,692 DEBUG tar: Removing leading `/' from member names 2025-06-14 04:08:04,692 DEBUG --- stderr --- 2025-06-14 04:08:04,692 DEBUG 2025-06-14 04:08:04,692 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-550/cmd.txt is small (1 lines), printing all lines: 2025-06-14 04:08:04,692 INFO ----- output ----- /lodestar/lodestar.py perf run-gatling --mvn-quiet --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idc.benchmark.Mix2024@tof-10" --results-folder="/results/gatling/benchmark-550" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx4G" --simulation-system-properties="-Dam_host=openam-tof-10.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-tof-10.forgeblocks.com -Dlogin_cookie=7573b68ed0f3690 -Dnum_users=10000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=550 -Duser_password=Pa_ssw0rd" ________________________________________________________________________________ [2025-06-14 04:08:04] benchmark-550 show_rc : Display rc order ________________________________________________________________________________ 2025-06-14 04:08:04,692 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-550/rc.txt is small (1 lines), printing all lines: 2025-06-14 04:08:04,692 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-06-14 04:08:04] benchmark-550 show_stdout : Display stdout order ________________________________________________________________________________ 2025-06-14 04:08:04,692 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/07_benchmark-550/stdout.txt is 1105 lines): 2025-06-14 04:08:04,693 INFO ----- output ----- NOTE: This file was too big so it was truncated. Only first 1000 and last 1000 lines are present. ------------------------------------------------------------ 2025-06-14 04:07:21,491 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpg6d0ttqa 2025-06-14 04:07:21,495 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-550" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx4G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-tof-10.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dduration=3600 -Didc=true -Didm_host=openam-tof-10.forgeblocks.com -Dlogin_cookie=7573b68ed0f3690 -Dnum_users=10000000 -Doauth2_client_id=endUserUIClient -Doauth2_redirect_uri=/enduser/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idc.benchmark.Mix2024 -DtestMode=open -Dthroughput=550 -Duser_password=**** 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-06-14 03:07:44 GMT 5s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=1640 KO=0 ) > authAsPolicyAdmin (OK=1 KO=0 ) > userRestlogin (OK=62 KO=0 ) > restLoginInitiate (OK=147 KO=0 ) > EvalPolicy (OK=58 KO=0 ) > restLoginUsernamePasswordCallback (OK=141 KO=0 ) > Skip 2FA (OK=44 KO=0 ) > UI-Post-Login - stage 1 (OK=46 KO=0 ) > User Authorize PKCE stage (OK=154 KO=0 ) > UI-Post-Login - stage 1 Redirect 1 (OK=42 KO=0 ) > UI AM idFromSession (OK=41 KO=0 ) > User AccessToken PKCE stage (OK=150 KO=0 ) > UI AM validateGoto (OK=41 KO=0 ) > RootUserinfo (OK=55 KO=0 ) > tokenUserinfo (OK=44 KO=0 ) > loginTreeWorkflow_restLoginInitiate (OK=11 KO=0 ) > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=11 KO=0 ) > UI-Post-Login - stage 2 (OK=37 KO=0 ) > UI-Post-Login - stage 2 Redirect 1 (OK=37 KO=0 ) > UI Enduser appAuthHelper1 (OK=33 KO=0 ) > UI Enduser appAuthHelper2 (OK=33 KO=0 ) > getIdToken (OK=11 KO=0 ) > getIdToken Redirect 1 (OK=10 KO=0 ) > UI-Post-Login - stage 3 (OK=28 KO=0 ) > createOrganization (OK=9 KO=0 ) > UI-Post-Login - stage 3 Redirect 1 (OK=27 KO=0 ) > queryOrganization (OK=8 KO=0 ) > UI Enduser sessioncheck (OK=25 KO=0 ) > UI IDM uiconfig (OK=25 KO=0 ) > UI IDM version (OK=25 KO=0 ) > UI IDM features (OK=25 KO=0 ) > UI IDM login (OK=25 KO=0 ) > UI IDM privilege (OK=25 KO=0 ) > deleteOrganization (OK=8 KO=0 ) > UI IDM dashboard (OK=24 KO=0 ) > UI IDM user (OK=24 KO=0 ) > UI IDM managed user schema (OK=23 KO=0 ) > UI IDM oidcToken (OK=21 KO=0 ) > userinfo (OK=20 KO=0 ) > UI-Logout (OK=19 KO=0 ) > UI-Logout Redirect 1 (OK=19 KO=0 ) > UI AM revoke2 (OK=17 KO=0 ) > UI AM endsession (OK=17 KO=0 ) > UI AM revoke1 (OK=17 KO=0 ) ---- getToken ------------------------------------------------------------------ [--------------------------------------------------------------------------] 0% waiting: 0 / active: 1 / done: 0 ---- idc.benchmark.Mix2024 ----------------------------------------------------- [- ] 0% waiting: 1987009 / active: 91 / done: 138 ================================================================================ ================================================================================ 2025-06-14 03:07:49 GMT 10s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=7899 KO=0 ) > authAsPolicyAdmin (OK=1 KO=0 ) > userRestlogin (OK=282 KO=0 ) > restLoginInitiate (OK=584 KO=0 ) > EvalPolicy (OK=271 KO=0 ) > restLoginUsernamePasswordCallback (OK=577 KO=0 ) > Skip 2FA (OK=194 KO=0 ) > UI-Post-Login - stage 1 (OK=181 KO=0 ) > User Authorize PKCE stage (OK=702 KO=0 ) > UI-Post-Login - stage 1 Redirect 1 (OK=179 KO=0 ) > UI AM idFromSession (OK=173 KO=0 ) > User AccessToken PKCE stage (OK=686 KO=0 ) > UI AM validateGoto (OK=173 KO=0 ) > RootUserinfo (OK=331 KO=0 ) > tokenUserinfo (OK=183 KO=0 ) > loginTreeWorkflow_restLoginInitiate (OK=28 KO=0 ) > loginTreeWorkflow_restLoginUsernamePasswordCallback (OK=27 KO=0 ) > UI-Post-Login - stage 2 (OK=164 KO=0 ) > UI-Post-Login - stage 2 Redirect 1 (OK=163 KO=0 ) > UI Enduser appAuthHelper1 (OK=158 KO=0 ) > UI Enduser appAuthHelper2 (OK=158 KO=0 ) > getIdToken (OK=66 KO=0 ) > getIdToken Redirect 1 (OK=63 KO=0 ) > UI-Post-Login - stage 3 (OK=146 KO=0 ) > createOrganization (OK=63 KO=0 ) > UI-Post-Login - stage 3 Redirect 1 (OK=142 KO=0 ) > queryOrganization (OK=62 KO=0 ) > UI Enduser sessioncheck (OK=138 KO=0 ) > UI IDM uiconfig (OK=138 KO=0 ) > UI IDM version (OK=137 KO=0 [...] UI IDM features: KO j.i.IOException: Premature close ========================= Session: Session(idc.benchmark.Mix2024,1969879,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI2ZWFiZjQ5OC0zMWYwLTQ0NzEtYTE4OC00MDY2NTgwNTU2ZmIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiOTVkNWVkZWItYmRlZC00NGY3LWFlMjAtNGY2OWI1OTk2MmJlLTE5OTAxNDQwMSIsInN1Ym5hbWUiOiI2ZWFiZjQ5OC0zMWYwLTQ0NzEtYTE4OC00MDY2NTgwNTU2ZmIiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoiRDVOa2hxbl9iNi1jc25CTGFLOUpvazlBZXhRIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU4LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsib3BlbmlkIiwiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OCwiaWF0IjoxNzQ5ODc0MDU4LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiJkWnF6cW5JOGFoaTlLMS01RnhyYVNMU3diMHcifQ.bNgsEWcZ5enNUCD2cR5gEdgeBUOAXpfGdclsu8kPfew, username -> user.9435586, tokenIdCookieName -> amlbcookie, policyEvalTokenId -> , access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI2ZWFiZjQ5OC0zMWYwLTQ0NzEtYTE4OC00MDY2NTgwNTU2ZmIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMWM0YWQwZmEtOTlmNS00MjZiLTgzNDQtNmYxNzg5NTU2ZTUzLTE5ODQxMTQyOSIsInN1Ym5hbWUiOiI2ZWFiZjQ5OC0zMWYwLTQ0NzEtYTE4OC00MDY2NTgwNTU2ZmIiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoidHZGdGZ3ZERiTDJENllCN1FyeERUOC1jTW9rIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU5LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OSwiaWF0IjoxNzQ5ODc0MDU5LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiJFQVM5cU1pcndzamx3U1pUTUhDdDNha0s4ZHMifQ.MjoQuUe_5qzy-uD-9IwW1mU7GL30WWnfOqAnk-ZJg7s, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@42cf1ce6, gatling.http.referer -> https://openam-tof-10.forgeblocks.com/am/, authcode -> tIXfs_Lg6mgpBxbHEpyJ0cTgxhM, callbacks -> {"tokenId":"ZEUm1IsX9XXPWrjqd1dQKqbHFL0.*AAJTSQACMDIAAlNLABxZZGl6cy9tR0orU2poYUFZTWZ6SDRSd0ZQeWc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-tof-10.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1749874058427), CookieKey(7573b68ed0f3690,openam-tof-10.forgeblocks.com,/) -> StoredCookie(7573b68ed0f3690=ZEUm1IsX9XXPWrjqd1dQKqbHFL0.*AAJTSQACMDIAAlNLABxZZGl6cy9tR0orU2poYUFZTWZ6SDRSd0ZQeWc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1749874058427))), user_uuid -> 6eabf498-31f0-4471-a188-4066580556fb, password -> Pa_ssw0rd, challenge -> 5cf1JPd87Wn3RyMChlPmv363smnmWj_D2wcGlzvys_I, gatling.http.cache.baseUrl -> https://openam-tof-10.forgeblocks.com:443, fake-ip -> 42.42.42.42, verifier -> NjAyOTEwMTA5NDgwNTk4NzY3OA, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@26ff7225, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@3e03fc1e, tokenId -> ZEUm1IsX9XXPWrjqd1dQKqbHFL0.*AAJTSQACMDIAAlNLABxZZGl6cy9tR0orU2poYUFZTWZ6SDRSd0ZQeWc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, 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),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$821/0x00007b26e06983d0@6010a3ba,io.netty.channel.epoll.EpollEventLoop@1eb6749b) ========================= HTTP request: GET https://openam-tof-10.forgeblocks.com/openidm/info/features?_queryFilter=true 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 referer: https://openam-tof-10.forgeblocks.com/am/ Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiI2ZWFiZjQ5OC0zMWYwLTQ0NzEtYTE4OC00MDY2NTgwNTU2ZmIiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiMWM0YWQwZmEtOTlmNS00MjZiLTgzNDQtNmYxNzg5NTU2ZTUzLTE5ODQxMTQyOSIsInN1Ym5hbWUiOiI2ZWFiZjQ5OC0zMWYwLTQ0NzEtYTE4OC00MDY2NTgwNTU2ZmIiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoidHZGdGZ3ZERiTDJENllCN1FyeERUOC1jTW9rIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU5LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OSwiaWF0IjoxNzQ5ODc0MDU5LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiJFQVM5cU1pcndzamx3U1pUTUhDdDNha0s4ZHMifQ.MjoQuUe_5qzy-uD-9IwW1mU7GL30WWnfOqAnk-ZJg7s Accept-Api-Version: accept: */* cookie: amlbcookie=01; 7573b68ed0f3690=ZEUm1IsX9XXPWrjqd1dQKqbHFL0.*AAJTSQACMDIAAlNLABxZZGl6cy9tR0orU2poYUFZTWZ6SDRSd0ZQeWc9AAR0eXBlAANDVFMAAlMxAAIwMQ..* host: openam-tof-10.forgeblocks.com cookies: amlbcookie=01, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 7573b68ed0f3690=ZEUm1IsX9XXPWrjqd1dQKqbHFL0.*AAJTSQACMDIAAlNLABxZZGl6cy9tR0orU2poYUFZTWZ6SDRSd0ZQeWc9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 05:07:39.701 [ERROR] i.g.c.a.b.SessionHookBuilder$$anon$1 - 'hook-160' crashed with 'j.u.NoSuchElementException: No attribute named 'callbacks' is defined', forwarding to the next one 05:07:39.701 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request EvalPolicy: No attribute named 'userTokenId' is defined 05:07:39.702 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'userRestlogin' failed for user 1970695: j.i.IOException: Premature close 05:07:39.702 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: userRestlogin: KO j.i.IOException: Premature close ========================= Session: Session(idc.benchmark.Mix2024,1970695,HashMap(gatling.http.cache.baseUrl -> https://openam-tof-10.forgeblocks.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@655d0e97, username -> user.1666624, tokenIdCookieName -> amlbcookie, policyEvalTokenId -> , gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@30418d9b, password -> Pa_ssw0rd),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$821/0x00007b26e06983d0@6010a3ba,io.netty.channel.epoll.EpollEventLoop@1eb6749b) ========================= HTTP request: POST https://openam-tof-10.forgeblocks.com/am/json/realms/root/realms/alpha/authenticate headers: Content-Type: apppliation/json Accept-API-Version: resource=2.0, protocol=1.0 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 X-OpenAM-Username: user.1666624 X-OpenAM-Password: Pa_ssw0rd accept: */* host: openam-tof-10.forgeblocks.com content-length: 0 ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 05:07:39.702 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request EvalPolicy: No attribute named 'userTokenId' is defined 05:07:39.702 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request ' UI IDM privilege' failed for user 1969920: j.i.IOException: Premature close 05:07:39.702 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: UI IDM privilege: KO j.i.IOException: Premature close ========================= Session: Session(idc.benchmark.Mix2024,1969920,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZmY3MTA1ZDYtMmNiMS00ZTE3LWIyYmItZDkyY2YyMDkzMDU4LTE5Njk0MzE0NiIsInN1Ym5hbWUiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoiM3BDdmJJai1kV2lPZ3pCUkVrV1NUMDM5THJFIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU4LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsib3BlbmlkIiwiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OCwiaWF0IjoxNzQ5ODc0MDU4LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiJDaXpCRnAxZ2QzMjNMaUE0cnVtc0JrRTdVQ2MifQ.phAJ0dffelVlzqCAI2APbSRlJ2cYfeU6GW-v0v8RBd0, username -> user.6138441, tokenIdCookieName -> amlbcookie, policyEvalTokenId -> , access_token_2 -> eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZmY3MTA1ZDYtMmNiMS00ZTE3LWIyYmItZDkyY2YyMDkzMDU4LTE5Njk0ODU0NCIsInN1Ym5hbWUiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoidkZ0LTF5aWhzd19FOWR2TGpUWlM1WlpLXzJNIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU5LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OSwiaWF0IjoxNzQ5ODc0MDU5LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiI1SVduY0Y1QURFbDNRRnpwcFpNbVZPZXFXdjgifQ.SDkwqxAl4el_YlL2TC501G5NrAVGYalQe_2hcL8jtPc, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@2862f28c, gatling.http.referer -> https://openam-tof-10.forgeblocks.com/am/, authcode -> ZEaTv8bmhlc-cZCPY75zOrYa16U, callbacks -> {"tokenId":"_UURdApPlu4ooOEW9boWsimbim0.*AAJTSQACMDIAAlNLABxzbEpPNHdCM05SejNrS2gzRzlEUmZZWXppekU9AAR0eXBlAANDVFMAAlMxAAIwMQ..*","successUrl":"/enduser/?realm=/alpha","realm":"/alpha"}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-tof-10.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1749874058511), CookieKey(7573b68ed0f3690,openam-tof-10.forgeblocks.com,/) -> StoredCookie(7573b68ed0f3690=_UURdApPlu4ooOEW9boWsimbim0.*AAJTSQACMDIAAlNLABxzbEpPNHdCM05SejNrS2gzRzlEUmZZWXppekU9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1749874058511))), user_uuid -> 233603b5-78bd-4308-855a-2651b75b70fd, password -> Pa_ssw0rd, challenge -> BOkUwognfOmwqAKqVL964C4dFxoTVHfLVZijaKjCuSs, gatling.http.cache.baseUrl -> https://openam-tof-10.forgeblocks.com:443, fake-ip -> 122.68.213.174, verifier -> LTEwNDMxNDcxOTkyNTc1MzI1OTc, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@3b9c151b, gatling.http.cache.contentCache -> io.gatling.core.util.cache.Cache@16370975, tokenId -> _UURdApPlu4ooOEW9boWsimbim0.*AAJTSQACMDIAAlNLABxzbEpPNHdCM05SejNrS2gzRzlEUmZZWXppekU9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, fake-user-agent -> Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.157 Safari/537.36),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$821/0x00007b26e06983d0@6010a3ba,io.netty.channel.epoll.EpollEventLoop@636e8cc) ========================= HTTP request: POST https://openam-tof-10.forgeblocks.com/openidm/privilege?_action=listPrivileges 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 referer: https://openam-tof-10.forgeblocks.com/am/ Authorization: Bearer eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzdWIiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiZmY3MTA1ZDYtMmNiMS00ZTE3LWIyYmItZDkyY2YyMDkzMDU4LTE5Njk0ODU0NCIsInN1Ym5hbWUiOiIyMzM2MDNiNS03OGJkLTQzMDgtODU1YS0yNjUxYjc1YjcwZmQiLCJpc3MiOiJodHRwczovL29wZW5hbS10b2YtMTAuZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIvcmVhbG1zL3Jvb3QvcmVhbG1zL2FscGhhIiwidG9rZW5OYW1lIjoiYWNjZXNzX3Rva2VuIiwidG9rZW5fdHlwZSI6IkJlYXJlciIsImF1dGhHcmFudElkIjoidkZ0LTF5aWhzd19FOWR2TGpUWlM1WlpLXzJNIiwibm9uY2UiOiIxMjM0IiwiY2xpZW50X2lkIjoiZW5kVXNlclVJQ2xpZW50IiwiYXVkIjoiZW5kVXNlclVJQ2xpZW50IiwibmJmIjoxNzQ5ODc0MDU5LCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQ5ODc0MDU4LCJyZWFsbSI6Ii9hbHBoYSIsImV4cCI6MTc0OTg3NzY1OSwiaWF0IjoxNzQ5ODc0MDU5LCJleHBpcmVzX2luIjozNjAwLCJqdGkiOiI1SVduY0Y1QURFbDNRRnpwcFpNbVZPZXFXdjgifQ.SDkwqxAl4el_YlL2TC501G5NrAVGYalQe_2hcL8jtPc Accept-Api-Version: accept: */* cookie: amlbcookie=01; 7573b68ed0f3690=_UURdApPlu4ooOEW9boWsimbim0.*AAJTSQACMDIAAlNLABxzbEpPNHdCM05SejNrS2gzRzlEUmZZWXppekU9AAR0eXBlAANDVFMAAlMxAAIwMQ..* origin: https://openam-tof-10.forgeblocks.com host: openam-tof-10.forgeblocks.com content-length: 0 cookies: amlbcookie=01, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None 7573b68ed0f3690=_UURdApPlu4ooOEW9boWsimbim0.*AAJTSQACMDIAAlNLABxzbEpPNHdCM05SejNrS2gzRzlEUmZZWXppekU9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, domain=openam-tof-10.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 05:07:39.703 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'userRestlogin' failed for user 1970687: j.i.IOException: Premature close 05:07:39.703 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: userRestlogin: KO j.i.IOException: Premature close ========================= Session: Session(idc.benchmark.Mix2024,1970687,HashMap(gatling.http.cache.baseUrl -> https://openam-tof-10.forgeblocks.com:443, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7ee890fe, username -> user.3575023, tokenIdCookieName -> amlbcookie, policyEvalTokenId -> , gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@278f4642, password -> Pa_ssw0rd),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$821/0x00007b26e06983d0@6010a3ba,io.netty.channel.epoll.EpollEventLoop@4e28bdd1) ========================= HTTP request: POST https://openam-tof-10.forgeblocks.com/am/json/realms/root/realms/alpha/authenticate headers: Content-Type: apppliation/json Accept-API-Version: resource=2.0, protocol=1.0 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 X-OpenAM-Username: user.3575023 X-OpenAM-Password: Pa_ssw0rd accept: */* host: openam-tof-10.forgeblocks.com content-length: 0 ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 05:07:39.703 [ERROR] i.g.h.a.HttpRequestAction - Failed to build request EvalPolicy: No attribute named 'userTokenId' is defined 2025-06-14 05:07:40,109 INFO Gatling simulation run ended successfully 2025-06-14 05:07:40,109 DEBUG No archive name provided. No archive will be created ________________________________________________________________________________ [2025-06-14 04:08:04] benchmark-550 show_stderr : Display stderr order ________________________________________________________________________________ 2025-06-14 04:08:04,693 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark/07_Scenario_benchmark/07_benchmark-550/stderr.txt is small (0 lines), printing all lines: 2025-06-14 04:08:04,693 INFO ----- output ----- ________________________________________________________________________________ [2025-06-14 04:08:04] benchmark-550 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-06-14 04:08:04] benchmark-550 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2025-06-14 04:08:04] benchmark-550 post : Post method ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped