--Task-- name: update-1700 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: idm 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: ['update-1600'] preceding_task: update-1600 options: {'testMode': 'open', 'testCase': 'idm.ManagedUsersUpdate', 'duration': 3600, 'throughput': 1700, 'java-opts': '-Dcsvfile=/results/idm/user_feeder.csv -Drealm=alpha'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock 2025-03-28 09:45:35,072 INFO 2025-03-28 09:45:35,072 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-03-28 09:45:35,072 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 09:45:35,387 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 09:45:35,388 INFO [loop_until]: OK (rc = 0) 2025-03-28 09:45:35,388 DEBUG --- stdout --- 2025-03-28 09:45:35,388 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 09:45:35,388 DEBUG --- stderr --- 2025-03-28 09:45:35,388 DEBUG 2025-03-28 09:45:35,388 INFO Lodestar assumed that full stack is deployed so will set all products host URLs in Gatling system properties 2025-03-28 09:45:35 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2025-03-28 09:45:35 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/update-1700/stdout.txt Pod log : /results/orders/update-1700 ________________________________________________________________________________ [2025-03-28 09:45:35] update-1700 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ /lodestar/lodestar.py perf run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idm.ManagedUsersUpdate@perf-200m" --results-folder="/results/gatling/update-1700" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx8G" --simulation-system-properties="-Dam_host=openam-perf-200m.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dcsvfile=/results/idm/user_feeder.csv -Dduration=3600 -Didc=true -Didm_host=openam-perf-200m.forgeblocks.com -Dlogin_cookie=15892ffd844ef80 -Dnum_users=50000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idm.ManagedUsersUpdate -DtestMode=open -Dthroughput=1700 -Duser_password=**** ________________________________________________________________________________ [2025-03-28 09:45:35] update-1700 create_order : Create order for overseer with the command to run ________________________________________________________________________________ Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/order.json { "name": "update-1700", "command": " /lodestar/lodestar.py perf run-gatling --simulation-class=\"com.forgerock.pyrock.Loadtest\" --run-description=\"idm.ManagedUsersUpdate@perf-200m\" --results-folder=\"/results/gatling/update-1700\" --no-reports --simulation-jvm-args=\"-XX:+ExitOnOutOfMemoryError -Xmx8G\" --simulation-system-properties=\"-Dam_host=openam-perf-200m.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dcsvfile=/results/idm/user_feeder.csv -Dduration=3600 -Didc=true -Didm_host=openam-perf-200m.forgeblocks.com -Dlogin_cookie=15892ffd844ef80 -Dnum_users=50000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idm.ManagedUsersUpdate -DtestMode=open -Dthroughput=1700 -Duser_password=T35!tr0c-k123\"", "out-dir": "/results/orders/update-1700" } ________________________________________________________________________________ [2025-03-28 09:45:35] update-1700 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-03-28 09:45:35,398 INFO 2025-03-28 09:45:35,398 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2025-03-28 09:45:35,399 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2025-03-28 09:45:35,615 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 09:45:35,615 INFO [loop_until]: OK (rc = 0) 2025-03-28 09:45:35,615 DEBUG --- stdout --- 2025-03-28 09:45:35,615 DEBUG WAITING 2025-03-28 09:45:35,615 DEBUG --- stderr --- 2025-03-28 09:45:35,615 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 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2025-03-28 09:45:35] update-1700 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2025-03-28 09:45:35,615 INFO 2025-03-28 09:45:35,615 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-03-28 09:45:35,615 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 09:45:35,892 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 09:45:35,892 INFO [loop_until]: OK (rc = 0) 2025-03-28 09:45:35,892 DEBUG --- stdout --- 2025-03-28 09:45:35,892 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 09:45:35,892 DEBUG --- stderr --- 2025-03-28 09:45:35,892 DEBUG 2025-03-28 09:45:35,892 INFO 2025-03-28 09:45:35,892 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/order.json overseer-0-59c948f569-rgrkv:/results/orders/order.json 2025-03-28 09:45:35,892 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 09:45:37,262 INFO [loop_until]: OK (rc = 0) 2025-03-28 09:45:37,262 DEBUG --- stdout --- 2025-03-28 09:45:37,262 DEBUG 2025-03-28 09:45:37,262 DEBUG --- stderr --- 2025-03-28 09:45:37,262 DEBUG Order has been successfully forwarded to overseer 2025-03-28 09:45:37 - INFO: Check if Gatling simulation started successfully 2025-03-28 09:45:37,262 INFO 2025-03-28 09:45:37,263 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv -- head --lines 5000 /results/orders/update-1700/stdout.txt | grep "Simulation com.forgerock.pyrock.Loadtest started..." 2025-03-28 09:45:37,263 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2025-03-28 09:45:37,994 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2025-03-28 09:45:43,725 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2025-03-28 09:45:49,453 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: Simulation com.forgerock.pyrock.Loadtest started... - retry 2025-03-28 09:45:55,180 INFO [loop_until]: Function succeeded after 17s (rc=0) - expected pattern found 2025-03-28 09:45:55,180 INFO [loop_until]: OK (rc = 0) 2025-03-28 09:45:55,180 DEBUG --- stdout --- 2025-03-28 09:45:55,180 DEBUG 2025-03-28 09:45:37,886 DEBUG Creating results folder /results/gatling/update-1700 2025-03-28 09:45:37,891 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpjo20ij4w 2025-03-28 09:45:37,894 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/update-1700" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx8G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-200m.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dcsvfile=/results/idm/user_feeder.csv -Dduration=3600 -Didc=true -Didm_host=openam-perf-200m.forgeblocks.com -Dlogin_cookie=15892ffd844ef80 -Dnum_users=50000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idm.ManagedUsersUpdate -DtestMode=open -Dthroughput=1700 -Duser_password=**** [INFO] Scanning for projects... [INFO] [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- [INFO] Building pyrock-gatling-simulation 3.12.0 [INFO] from pom.xml [INFO] --------------------------------[ jar ]--------------------------------- [INFO] [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> [INFO] [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- [INFO] skip non existing resourceDirectory /tmp/tmpjo20ij4w/src/main/resources [INFO] [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- [INFO] No sources to compile [INFO] [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- [INFO] Copying 2 resources from src/test/resources to target/test-classes [INFO] [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- [INFO] Nothing to compile - all classes are up to date. [INFO] [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- [INFO] compiling 38 Scala sources to /tmp/tmpjo20ij4w/target/test-classes ... [INFO] compile in 8.5 s [INFO] [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< [INFO] [INFO] [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- [INFO] Running simulation com.forgerock.pyrock.Loadtest. Gatling 3.13.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. Set idVarName to id Simulation com.forgerock.pyrock.Loadtest started... 2025-03-28 09:45:55,180 DEBUG --- stderr --- 2025-03-28 09:45:55,180 DEBUG ________________________________________________________________________________ [2025-03-28 09:45:55] update-1700 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m. Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 03s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 04s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 05s, retry Order is running since 40m 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-03-28 10:46:04] update-1700 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2025-03-28 10:46:04,110 INFO 2025-03-28 10:46:04,110 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-03-28 10:46:04,110 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 10:46:04,419 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 10:46:04,419 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:04,419 DEBUG --- stdout --- 2025-03-28 10:46:04,419 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 10:46:04,419 DEBUG --- stderr --- 2025-03-28 10:46:04,419 DEBUG 2025-03-28 10:46:04,419 INFO 2025-03-28 10:46:04,419 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv -- rm -f /results/orders/order.json 2025-03-28 10:46:04,419 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 10:46:05,191 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:05,191 DEBUG --- stdout --- 2025-03-28 10:46:05,191 DEBUG 2025-03-28 10:46:05,191 DEBUG --- stderr --- 2025-03-28 10:46:05,191 DEBUG Order ran during 1h ________________________________________________________________________________ [2025-03-28 10:46:05] update-1700 download_logs : Download individual order files ________________________________________________________________________________ 2025-03-28 10:46:05 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/order.json 2025-03-28 10:46:05,191 INFO 2025-03-28 10:46:05,191 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-03-28 10:46:05,191 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 10:46:05,471 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 10:46:05,471 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:05,471 DEBUG --- stdout --- 2025-03-28 10:46:05,471 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 10:46:05,471 DEBUG --- stderr --- 2025-03-28 10:46:05,471 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 10:46:05,472 INFO 2025-03-28 10:46:05,472 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/update-1700/rc.txt" ]; then echo "/results/orders/update-1700/rc.txt file found"; exit 0; else echo "/results/orders/update-1700/rc.txt file not found"; exit 1; fi' | grep "/results/orders/update-1700/rc.txt file found" 2025-03-28 10:46:05,472 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 10:46:06,285 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 10:46:06,285 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:06,285 DEBUG --- stdout --- 2025-03-28 10:46:06,285 DEBUG /results/orders/update-1700/rc.txt file found 2025-03-28 10:46:06,285 DEBUG --- stderr --- 2025-03-28 10:46:06,285 DEBUG 2025-03-28 10:46:06,285 INFO 2025-03-28 10:46:06,285 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/update-1700/rc.txt 2025-03-28 10:46:07,091 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 10:46:07,091 DEBUG --- stdout --- 2025-03-28 10:46:07,091 DEBUG 4 /results/orders/update-1700/rc.txt 2025-03-28 10:46:07,091 DEBUG --- stderr --- 2025-03-28 10:46:07,091 DEBUG 2025-03-28 10:46:07,091 INFO - Download file (size 4k) source : /results/orders/update-1700/rc.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/rc.txt 2025-03-28 10:46:07,092 INFO 2025-03-28 10:46:07,092 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/update-1700/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/rc.txt 2025-03-28 10:46:07,092 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 10:46:07,853 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:07,853 DEBUG --- stdout --- 2025-03-28 10:46:07,853 DEBUG tar: Removing leading `/' from member names 2025-03-28 10:46:07,853 DEBUG --- stderr --- 2025-03-28 10:46:07,853 DEBUG 2025-03-28 10:46:07,853 INFO 2025-03-28 10:46:07,853 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2025-03-28 10:46:07,853 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 10:46:08,130 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 10:46:08,130 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:08,130 DEBUG --- stdout --- 2025-03-28 10:46:08,130 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 10:46:08,130 DEBUG --- stderr --- 2025-03-28 10:46:08,130 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 10:46:08,131 INFO 2025-03-28 10:46:08,131 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/update-1700/stderr.txt" ]; then echo "/results/orders/update-1700/stderr.txt file found"; exit 0; else echo "/results/orders/update-1700/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/update-1700/stderr.txt file found" 2025-03-28 10:46:08,131 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 10:46:08,928 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 10:46:08,928 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:08,928 DEBUG --- stdout --- 2025-03-28 10:46:08,928 DEBUG /results/orders/update-1700/stderr.txt file found 2025-03-28 10:46:08,928 DEBUG --- stderr --- 2025-03-28 10:46:08,928 DEBUG 2025-03-28 10:46:08,928 INFO 2025-03-28 10:46:08,929 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/update-1700/stderr.txt 2025-03-28 10:46:09,722 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 10:46:09,722 DEBUG --- stdout --- 2025-03-28 10:46:09,722 DEBUG 0 /results/orders/update-1700/stderr.txt 2025-03-28 10:46:09,722 DEBUG --- stderr --- 2025-03-28 10:46:09,722 DEBUG 2025-03-28 10:46:09,722 INFO - Download file (size 0k) source : /results/orders/update-1700/stderr.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/stderr.txt 2025-03-28 10:46:09,723 INFO 2025-03-28 10:46:09,723 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/update-1700/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/stderr.txt 2025-03-28 10:46:09,723 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 10:46:10,481 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:10,481 DEBUG --- stdout --- 2025-03-28 10:46:10,481 DEBUG tar: Removing leading `/' from member names 2025-03-28 10:46:10,481 DEBUG --- stderr --- 2025-03-28 10:46:10,481 DEBUG 2025-03-28 10:46:10,481 INFO 2025-03-28 10:46:10,481 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-03-28 10:46:10,481 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 10:46:10,773 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 10:46:10,773 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:10,773 DEBUG --- stdout --- 2025-03-28 10:46:10,773 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 10:46:10,773 DEBUG --- stderr --- 2025-03-28 10:46:10,773 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 10:46:10,774 INFO 2025-03-28 10:46:10,774 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/update-1700/stdout.txt" ]; then echo "/results/orders/update-1700/stdout.txt file found"; exit 0; else echo "/results/orders/update-1700/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/update-1700/stdout.txt file found" 2025-03-28 10:46:10,774 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 10:46:11,587 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 10:46:11,587 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:11,587 DEBUG --- stdout --- 2025-03-28 10:46:11,587 DEBUG /results/orders/update-1700/stdout.txt file found 2025-03-28 10:46:11,587 DEBUG --- stderr --- 2025-03-28 10:46:11,587 DEBUG 2025-03-28 10:46:11,587 INFO 2025-03-28 10:46:11,588 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/update-1700/stdout.txt 2025-03-28 10:46:12,389 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 10:46:12,389 DEBUG --- stdout --- 2025-03-28 10:46:12,389 DEBUG 2256 /results/orders/update-1700/stdout.txt 2025-03-28 10:46:12,389 DEBUG --- stderr --- 2025-03-28 10:46:12,389 DEBUG 2025-03-28 10:46:12,389 INFO - Download file (size 2256k) source : /results/orders/update-1700/stdout.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/stdout.txt 2025-03-28 10:46:12,390 INFO 2025-03-28 10:46:12,390 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/update-1700/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/stdout.txt 2025-03-28 10:46:12,390 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 10:46:13,580 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:13,581 DEBUG --- stdout --- 2025-03-28 10:46:13,581 DEBUG tar: Removing leading `/' from member names 2025-03-28 10:46:13,581 DEBUG --- stderr --- 2025-03-28 10:46:13,581 DEBUG ________________________________________________________________________________ [2025-03-28 10:46:13] update-1700 show_cmd : Display order command ________________________________________________________________________________ 2025-03-28 10:46:13,581 INFO 2025-03-28 10:46:13,581 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-03-28 10:46:13,581 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2025-03-28 10:46:13,859 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2025-03-28 10:46:13,859 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:13,859 DEBUG --- stdout --- 2025-03-28 10:46:13,859 DEBUG overseer-0-59c948f569-rgrkv 2025-03-28 10:46:13,859 DEBUG --- stderr --- 2025-03-28 10:46:13,860 DEBUG Checking pod: overseer-0-59c948f569-rgrkv for file exists, if not wait. 2025-03-28 10:46:13,860 INFO 2025-03-28 10:46:13,860 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- bash -c 'if [ -f "/results/orders/update-1700/cmd.txt" ]; then echo "/results/orders/update-1700/cmd.txt file found"; exit 0; else echo "/results/orders/update-1700/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/update-1700/cmd.txt file found" 2025-03-28 10:46:13,860 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2025-03-28 10:46:14,658 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2025-03-28 10:46:14,659 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:14,659 DEBUG --- stdout --- 2025-03-28 10:46:14,659 DEBUG /results/orders/update-1700/cmd.txt file found 2025-03-28 10:46:14,659 DEBUG --- stderr --- 2025-03-28 10:46:14,659 DEBUG 2025-03-28 10:46:14,659 INFO 2025-03-28 10:46:14,659 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-59c948f569-rgrkv --stdin -- du -k /results/orders/update-1700/cmd.txt 2025-03-28 10:46:15,455 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2025-03-28 10:46:15,455 DEBUG --- stdout --- 2025-03-28 10:46:15,455 DEBUG 4 /results/orders/update-1700/cmd.txt 2025-03-28 10:46:15,455 DEBUG --- stderr --- 2025-03-28 10:46:15,455 DEBUG 2025-03-28 10:46:15,455 INFO - Download file (size 4k) source : /results/orders/update-1700/cmd.txt @ overseer-0-59c948f569-rgrkv destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/cmd.txt 2025-03-28 10:46:15,456 INFO 2025-03-28 10:46:15,456 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-59c948f569-rgrkv:/results/orders/update-1700/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/cmd.txt 2025-03-28 10:46:15,456 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2025-03-28 10:46:16,178 INFO [loop_until]: OK (rc = 0) 2025-03-28 10:46:16,178 DEBUG --- stdout --- 2025-03-28 10:46:16,178 DEBUG tar: Removing leading `/' from member names 2025-03-28 10:46:16,178 DEBUG --- stderr --- 2025-03-28 10:46:16,178 DEBUG 2025-03-28 10:46:16,178 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/cmd.txt is small (1 lines), printing all lines: 2025-03-28 10:46:16,178 INFO ----- output ----- /lodestar/lodestar.py perf run-gatling --simulation-class="com.forgerock.pyrock.Loadtest" --run-description="idm.ManagedUsersUpdate@perf-200m" --results-folder="/results/gatling/update-1700" --no-reports --simulation-jvm-args="-XX:+ExitOnOutOfMemoryError -Xmx8G" --simulation-system-properties="-Dam_host=openam-perf-200m.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=M@ythe4th -DamadminUsername=xiaosong.lou@pingidentity.com -Dcsvfile=/results/idm/user_feeder.csv -Dduration=3600 -Didc=true -Didm_host=openam-perf-200m.forgeblocks.com -Dlogin_cookie=15892ffd844ef80 -Dnum_users=50000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idm.ManagedUsersUpdate -DtestMode=open -Dthroughput=1700 -Duser_password=T35!tr0c-k123" ________________________________________________________________________________ [2025-03-28 10:46:16] update-1700 show_rc : Display rc order ________________________________________________________________________________ 2025-03-28 10:46:16,179 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/rc.txt is small (1 lines), printing all lines: 2025-03-28 10:46:16,179 INFO ----- output ----- CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) ________________________________________________________________________________ [2025-03-28 10:46:16] update-1700 show_stdout : Display stdout order ________________________________________________________________________________ 2025-03-28 10:46:16,182 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/stdout.txt is 25724 lines): 2025-03-28 10:46:16,186 INFO ----- output ----- 2025-03-28 09:45:37,886 DEBUG Creating results folder /results/gatling/update-1700 2025-03-28 09:45:37,891 INFO pom.xml and Gatling Scala files copied from /home/jenkins/lodestar/pyrock/shared/clients/gatling to temporary folder /tmp/tmpjo20ij4w 2025-03-28 09:45:37,894 INFO Running Gatling simulation with command: mvn gatling:test --batch-mode -Dgatling.simulationClass="com.forgerock.pyrock.Loadtest" -Dgatling.runDescription="..." -Dgatling.resultsFolder="/results/gatling/update-1700" -Dgatling.noReports=true -Dgatling.jvmArgs="-XX:+ExitOnOutOfMemoryError,-Xmx8G" -Dgatling.propagateSystemProperties=true -Dam_host=openam-perf-200m.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dcsvfile=/results/idm/user_feeder.csv -Dduration=3600 -Didc=true -Didm_host=openam-perf-200m.forgeblocks.com -Dlogin_cookie=15892ffd844ef80 -Dnum_users=50000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drealm=alpha -DtestCase=idm.ManagedUsersUpdate -DtestMode=open -Dthroughput=1700 -Duser_password=**** [INFO] Scanning for projects... [INFO] [INFO] -----------< com.forgerock.pyrock:pyrock-gatling-simulation >----------- [INFO] Building pyrock-gatling-simulation 3.12.0 [INFO] from pom.xml [INFO] --------------------------------[ jar ]--------------------------------- [INFO] [INFO] >>> gatling:4.9.6:test (default-cli) > test-compile @ pyrock-gatling-simulation >>> [INFO] [INFO] --- resources:3.3.1:resources (default-resources) @ pyrock-gatling-simulation --- [INFO] skip non existing resourceDirectory /tmp/tmpjo20ij4w/src/main/resources [INFO] [INFO] --- compiler:3.13.0:compile (default-compile) @ pyrock-gatling-simulation --- [INFO] No sources to compile [INFO] [INFO] --- resources:3.3.1:testResources (default-testResources) @ pyrock-gatling-simulation --- [INFO] Copying 2 resources from src/test/resources to target/test-classes [INFO] [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ pyrock-gatling-simulation --- [INFO] Nothing to compile - all classes are up to date. [INFO] [INFO] --- scala:4.9.2:testCompile (default) @ pyrock-gatling-simulation --- [INFO] compiling 38 Scala sources to /tmp/tmpjo20ij4w/target/test-classes ... [INFO] compile in 8.5 s [INFO] [INFO] <<< gatling:4.9.6:test (default-cli) < test-compile @ pyrock-gatling-simulation <<< [INFO] [INFO] [INFO] --- gatling:4.9.6:test (default-cli) @ pyrock-gatling-simulation --- [INFO] Running simulation com.forgerock.pyrock.Loadtest. Gatling 3.13.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. Set idVarName to id Simulation com.forgerock.pyrock.Loadtest started... ================================================================================ 2025-03-28 09:45:56 GMT 5s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=692 KO=0 ) > restLoginInitiate (OK=1 KO=0 ) > restLoginUsernamePasswordCallback (OK=1 KO=0 ) > Skip 2FA (OK=1 KO=0 ) > User Authorize PKCE stage (OK=1 KO=0 ) > User AccessToken PKCE stage (OK=1 KO=0 ) > UpdateManagedUser (OK=687 KO=0 ) ---- getToken ------------------------------------------------------------------ [--------------------------------------------------------------------------] 0% waiting: 0 / active: 1 / done: 0 ---- idm.ManagedUsersUpdate ---------------------------------------------------- [- ] 0% waiting: 6151397 / active: 20 / done: 687 ================================================================================ ================================================================================ 2025-03-28 09:46:01 GMT 10s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=2789 KO=0 ) > restLoginInitiate (OK=1 KO=0 ) > restLoginUsernamePasswordCallback (OK=1 KO=0 ) > Skip 2FA (OK=1 KO=0 ) > User Authorize PKCE stage (OK=1 KO=0 ) > User AccessToken PKCE stage (OK=1 KO=0 ) > UpdateManagedUser (OK=2784 KO=0 ) ---- getToken ------------------------------------------------------------------ [--------------------------------------------------------------------------] 0% waiting: 0 / active: 1 / done: 0 ---- idm.ManagedUsersUpdate ---------------------------------------------------- [- ] 0% waiting: 6149274 / active: 46 / done: 2784 ================================================================================ ================================================================================ 2025-03-28 09:46:06 GMT 15s elapsed ---- Requests ------------------------------------------------------------------ > Global (OK=6306 KO=0 ) > restLoginInitiate (OK=1 KO=0 ) > restLoginUsernamePasswordCallback (OK=1 KO=0 ) > Skip 2FA (OK=1 KO=0 ) > User Authorize PKCE stage (OK=1 KO=0 ) > User AccessToken PKCE stage (OK=1 KO=0 ) > UpdateManagedUser (OK=6301 KO=0 ) ---- getToken ------------------------------------------------------------------ [--------------------------------------------------------------------------] 0% waiting: 0 / active: 1 / done: 0 ---- idm.ManagedUsersUpdate ---------------------------------------------------- [- ] 0% waiting: 6145733 / active: 70 / done: 6301 ================================================================================ ================================================================================ 2025-03-28 09:46:11 GMT 20s elapsed [...] 10:45:51.292 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'UpdateManagedUser' failed for user 6101162: j.n.c.ClosedChannelException 10:45:51.292 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'UpdateManagedUser' failed for user 6101107: j.i.IOException: Premature close 10:45:51.292 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: UpdateManagedUser: KO j.n.c.ClosedChannelException ========================= Session: Session(idm.ManagedUsersUpdate,6101162,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJraWQiOiJ1QVQ1a1VCbnhzUmZLblRwNUw4bDFpNkdyRkU9IiwiYWxnIjoiUlMyNTYifQ.eyJzdWIiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNTcyNzU2YzEtN2UwMC00ODRmLWEyMDQtNDc5MTVjZDE4MDA4LTY2MDI5OSIsInN1Ym5hbWUiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLTIwMG0uZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiIzWUhiWHpLTUVOaVEyQ2Z3U1VJR2ZHZ0NYSWMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJpZG1BZG1pbkNsaWVudCIsImF1ZCI6ImlkbUFkbWluQ2xpZW50IiwibmJmIjoxNzQzMTU4NjUyLCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsib3BlbmlkIiwiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQzMTU4NjUyLCJyZWFsbSI6Ii8iLCJleHAiOjE3NDMxNjIyNTIsImlhdCI6MTc0MzE1ODY1MiwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoidWd3UEFBU1RPelRyRXlTM3JYMjlUVEg0MGMwIn0.ksVgiQOwcpG733x7ekd0vXzS5KkcvPg7PB7kHgmlABau9IZQnIAwP0e0EAnMX-Y0wHt7WNb4nMD8Pgt5xaP5FDi0XjL4_Y5hrsiAXDp1eLqz_186pHES_JQNFxewqq6dh9c3gPMPNXRa-ZyPXoGV5Ck5jC-RIa_73Cl5fe6UTubNP_CXV759qO2j6bnwpIAKKfWkpJTdUdfxAfq9wK-sLCqC_QQkeTROx1EXJ-akxthBRPnqj4ICDQ1rXgekWinMvubi_mpCnvZsYTHA292gjNnD2sT5xaGp0OoyRUjvY-U_m7bubRMX8Be0txewRDzEyrD9rpRoutENZLO0FEEIQw, username -> user.152109957, tokenIdCookieName -> 15892ffd844ef80, id -> 005ccd22-a7ba-44be-a29f-f3d6bc251879, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@77d4f993, givenname -> Melita, tokenId -> 5FIyCsP2gd6_5NZhGQygJIiLmVY.*AAJTSQACMDIAAlNLABxxeWdNQUdFbkFrc09HZzhVUnZDRmhRTXdtajg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, familyname -> Iezzi2732, telNum -> 363-943-1634, challenge -> wGmYDRpSXfo36qsThKHEt8sX0NPv2AhMn6dI7SdbMo4, gatling.http.cache.baseUrl -> https://openam-perf-200m.forgeblocks.com:443, email -> user.152109957@example.com, verifier -> ODEzMDMwNTMwODkzMzcyMjk4Mw, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@3f77ae7e),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$822/0x000077fb7869a6b0@70e93ed5,io.netty.channel.epoll.EpollEventLoop@2bef51f2) ========================= HTTP request: PUT https://openam-perf-200m.forgeblocks.com/openidm/managed/alpha_user/005ccd22-a7ba-44be-a29f-f3d6bc251879 headers: if-match: * Content-Type: application/json accept: application/json 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 Authorization: Bearer eyJ0eXAiOiJKV1QiLCJraWQiOiJ1QVQ1a1VCbnhzUmZLblRwNUw4bDFpNkdyRkU9IiwiYWxnIjoiUlMyNTYifQ.eyJzdWIiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNTcyNzU2YzEtN2UwMC00ODRmLWEyMDQtNDc5MTVjZDE4MDA4LTY2MDI5OSIsInN1Ym5hbWUiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLTIwMG0uZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiIzWUhiWHpLTUVOaVEyQ2Z3U1VJR2ZHZ0NYSWMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJpZG1BZG1pbkNsaWVudCIsImF1ZCI6ImlkbUFkbWluQ2xpZW50IiwibmJmIjoxNzQzMTU4NjUyLCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsib3BlbmlkIiwiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQzMTU4NjUyLCJyZWFsbSI6Ii8iLCJleHAiOjE3NDMxNjIyNTIsImlhdCI6MTc0MzE1ODY1MiwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoidWd3UEFBU1RPelRyRXlTM3JYMjlUVEg0MGMwIn0.ksVgiQOwcpG733x7ekd0vXzS5KkcvPg7PB7kHgmlABau9IZQnIAwP0e0EAnMX-Y0wHt7WNb4nMD8Pgt5xaP5FDi0XjL4_Y5hrsiAXDp1eLqz_186pHES_JQNFxewqq6dh9c3gPMPNXRa-ZyPXoGV5Ck5jC-RIa_73Cl5fe6UTubNP_CXV759qO2j6bnwpIAKKfWkpJTdUdfxAfq9wK-sLCqC_QQkeTROx1EXJ-akxthBRPnqj4ICDQ1rXgekWinMvubi_mpCnvZsYTHA292gjNnD2sT5xaGp0OoyRUjvY-U_m7bubRMX8Be0txewRDzEyrD9rpRoutENZLO0FEEIQw host: openam-perf-200m.forgeblocks.com body:StringChunksRequestBody{charset=UTF-8, content={ "_id" : "005ccd22-a7ba-44be-a29f-f3d6bc251879", "givenName" : "Melita", "sn" : "Iezzi2732", "mail" : "user.152109957@example.com", "telephoneNumber" : "363-943-1634", "description" : "Updated Managed User", "userName" : "user.152109957" }} ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 10:45:51.292 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'UpdateManagedUser' failed for user 6101132: j.i.IOException: Premature close 10:45:51.292 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: UpdateManagedUser: KO j.i.IOException: Premature close ========================= Session: Session(idm.ManagedUsersUpdate,6101107,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJraWQiOiJ1QVQ1a1VCbnhzUmZLblRwNUw4bDFpNkdyRkU9IiwiYWxnIjoiUlMyNTYifQ.eyJzdWIiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNTcyNzU2YzEtN2UwMC00ODRmLWEyMDQtNDc5MTVjZDE4MDA4LTY2MDI5OSIsInN1Ym5hbWUiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLTIwMG0uZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiIzWUhiWHpLTUVOaVEyQ2Z3U1VJR2ZHZ0NYSWMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJpZG1BZG1pbkNsaWVudCIsImF1ZCI6ImlkbUFkbWluQ2xpZW50IiwibmJmIjoxNzQzMTU4NjUyLCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsib3BlbmlkIiwiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQzMTU4NjUyLCJyZWFsbSI6Ii8iLCJleHAiOjE3NDMxNjIyNTIsImlhdCI6MTc0MzE1ODY1MiwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoidWd3UEFBU1RPelRyRXlTM3JYMjlUVEg0MGMwIn0.ksVgiQOwcpG733x7ekd0vXzS5KkcvPg7PB7kHgmlABau9IZQnIAwP0e0EAnMX-Y0wHt7WNb4nMD8Pgt5xaP5FDi0XjL4_Y5hrsiAXDp1eLqz_186pHES_JQNFxewqq6dh9c3gPMPNXRa-ZyPXoGV5Ck5jC-RIa_73Cl5fe6UTubNP_CXV759qO2j6bnwpIAKKfWkpJTdUdfxAfq9wK-sLCqC_QQkeTROx1EXJ-akxthBRPnqj4ICDQ1rXgekWinMvubi_mpCnvZsYTHA292gjNnD2sT5xaGp0OoyRUjvY-U_m7bubRMX8Be0txewRDzEyrD9rpRoutENZLO0FEEIQw, username -> user.193993752, tokenIdCookieName -> 15892ffd844ef80, id -> 005cc8ba-e349-43eb-8634-8b165d481458, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@62878f90, givenname -> Adrienne, tokenId -> 5FIyCsP2gd6_5NZhGQygJIiLmVY.*AAJTSQACMDIAAlNLABxxeWdNQUdFbkFrc09HZzhVUnZDRmhRTXdtajg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, familyname -> Ness5853, telNum -> 388-345-6002, challenge -> wGmYDRpSXfo36qsThKHEt8sX0NPv2AhMn6dI7SdbMo4, gatling.http.cache.baseUrl -> https://openam-perf-200m.forgeblocks.com:443, email -> user.193993752@example.com, verifier -> ODEzMDMwNTMwODkzMzcyMjk4Mw, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@6ebf2fc5),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$822/0x000077fb7869a6b0@70e93ed5,io.netty.channel.epoll.EpollEventLoop@12dae582) ========================= HTTP request: PUT https://openam-perf-200m.forgeblocks.com/openidm/managed/alpha_user/005cc8ba-e349-43eb-8634-8b165d481458 headers: if-match: * Content-Type: application/json accept: application/json 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 Authorization: Bearer eyJ0eXAiOiJKV1QiLCJraWQiOiJ1QVQ1a1VCbnhzUmZLblRwNUw4bDFpNkdyRkU9IiwiYWxnIjoiUlMyNTYifQ.eyJzdWIiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNTcyNzU2YzEtN2UwMC00ODRmLWEyMDQtNDc5MTVjZDE4MDA4LTY2MDI5OSIsInN1Ym5hbWUiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLTIwMG0uZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiIzWUhiWHpLTUVOaVEyQ2Z3U1VJR2ZHZ0NYSWMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJpZG1BZG1pbkNsaWVudCIsImF1ZCI6ImlkbUFkbWluQ2xpZW50IiwibmJmIjoxNzQzMTU4NjUyLCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsib3BlbmlkIiwiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQzMTU4NjUyLCJyZWFsbSI6Ii8iLCJleHAiOjE3NDMxNjIyNTIsImlhdCI6MTc0MzE1ODY1MiwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoidWd3UEFBU1RPelRyRXlTM3JYMjlUVEg0MGMwIn0.ksVgiQOwcpG733x7ekd0vXzS5KkcvPg7PB7kHgmlABau9IZQnIAwP0e0EAnMX-Y0wHt7WNb4nMD8Pgt5xaP5FDi0XjL4_Y5hrsiAXDp1eLqz_186pHES_JQNFxewqq6dh9c3gPMPNXRa-ZyPXoGV5Ck5jC-RIa_73Cl5fe6UTubNP_CXV759qO2j6bnwpIAKKfWkpJTdUdfxAfq9wK-sLCqC_QQkeTROx1EXJ-akxthBRPnqj4ICDQ1rXgekWinMvubi_mpCnvZsYTHA292gjNnD2sT5xaGp0OoyRUjvY-U_m7bubRMX8Be0txewRDzEyrD9rpRoutENZLO0FEEIQw host: openam-perf-200m.forgeblocks.com content-length: 273 body:StringChunksRequestBody{charset=UTF-8, content={ "_id" : "005cc8ba-e349-43eb-8634-8b165d481458", "givenName" : "Adrienne", "sn" : "Ness5853", "mail" : "user.193993752@example.com", "telephoneNumber" : "388-345-6002", "description" : "Updated Managed User", "userName" : "user.193993752" }} ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< 10:45:51.292 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - >>>>>>>>>>>>>>>>>>>>>>>>>> Request: UpdateManagedUser: KO j.i.IOException: Premature close ========================= Session: Session(idm.ManagedUsersUpdate,6101132,HashMap(access_token -> eyJ0eXAiOiJKV1QiLCJraWQiOiJ1QVQ1a1VCbnhzUmZLblRwNUw4bDFpNkdyRkU9IiwiYWxnIjoiUlMyNTYifQ.eyJzdWIiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNTcyNzU2YzEtN2UwMC00ODRmLWEyMDQtNDc5MTVjZDE4MDA4LTY2MDI5OSIsInN1Ym5hbWUiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLTIwMG0uZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiIzWUhiWHpLTUVOaVEyQ2Z3U1VJR2ZHZ0NYSWMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJpZG1BZG1pbkNsaWVudCIsImF1ZCI6ImlkbUFkbWluQ2xpZW50IiwibmJmIjoxNzQzMTU4NjUyLCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsib3BlbmlkIiwiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQzMTU4NjUyLCJyZWFsbSI6Ii8iLCJleHAiOjE3NDMxNjIyNTIsImlhdCI6MTc0MzE1ODY1MiwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoidWd3UEFBU1RPelRyRXlTM3JYMjlUVEg0MGMwIn0.ksVgiQOwcpG733x7ekd0vXzS5KkcvPg7PB7kHgmlABau9IZQnIAwP0e0EAnMX-Y0wHt7WNb4nMD8Pgt5xaP5FDi0XjL4_Y5hrsiAXDp1eLqz_186pHES_JQNFxewqq6dh9c3gPMPNXRa-ZyPXoGV5Ck5jC-RIa_73Cl5fe6UTubNP_CXV759qO2j6bnwpIAKKfWkpJTdUdfxAfq9wK-sLCqC_QQkeTROx1EXJ-akxthBRPnqj4ICDQ1rXgekWinMvubi_mpCnvZsYTHA292gjNnD2sT5xaGp0OoyRUjvY-U_m7bubRMX8Be0txewRDzEyrD9rpRoutENZLO0FEEIQw, username -> user.112612363, tokenIdCookieName -> 15892ffd844ef80, id -> 005cca7f-d2b8-402d-8c5e-034b34962279, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@4ff245a7, givenname -> Yueh, tokenId -> 5FIyCsP2gd6_5NZhGQygJIiLmVY.*AAJTSQACMDIAAlNLABxxeWdNQUdFbkFrc09HZzhVUnZDRmhRTXdtajg9AAR0eXBlAANDVFMAAlMxAAIwMQ..*, familyname -> Akbas, telNum -> 774-816-546, challenge -> wGmYDRpSXfo36qsThKHEt8sX0NPv2AhMn6dI7SdbMo4, gatling.http.cache.baseUrl -> https://openam-perf-200m.forgeblocks.com:443, email -> user.112612363@example.com, verifier -> ODEzMDMwNTMwODkzMzcyMjk4Mw, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@7edfdc0b),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$822/0x000077fb7869a6b0@70e93ed5,io.netty.channel.epoll.EpollEventLoop@30f5a68a) ========================= HTTP request: PUT https://openam-perf-200m.forgeblocks.com/openidm/managed/alpha_user/005cca7f-d2b8-402d-8c5e-034b34962279 headers: if-match: * Content-Type: application/json accept: application/json 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 Authorization: Bearer eyJ0eXAiOiJKV1QiLCJraWQiOiJ1QVQ1a1VCbnhzUmZLblRwNUw4bDFpNkdyRkU9IiwiYWxnIjoiUlMyNTYifQ.eyJzdWIiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJjdHMiOiJPQVVUSDJfU1RBVEVMRVNTX0dSQU5UIiwiYXV0aF9sZXZlbCI6MCwiYXVkaXRUcmFja2luZ0lkIjoiNTcyNzU2YzEtN2UwMC00ODRmLWEyMDQtNDc5MTVjZDE4MDA4LTY2MDI5OSIsInN1Ym5hbWUiOiJjNDI0ZjVmZC0zNjJlLTQzOTctYTg3NC0xYzIzMWU5M2UwZGMiLCJpc3MiOiJodHRwczovL29wZW5hbS1wZXJmLTIwMG0uZm9yZ2VibG9ja3MuY29tOjQ0My9hbS9vYXV0aDIiLCJ0b2tlbk5hbWUiOiJhY2Nlc3NfdG9rZW4iLCJ0b2tlbl90eXBlIjoiQmVhcmVyIiwiYXV0aEdyYW50SWQiOiIzWUhiWHpLTUVOaVEyQ2Z3U1VJR2ZHZ0NYSWMiLCJub25jZSI6IjEyMzQiLCJjbGllbnRfaWQiOiJpZG1BZG1pbkNsaWVudCIsImF1ZCI6ImlkbUFkbWluQ2xpZW50IiwibmJmIjoxNzQzMTU4NjUyLCJncmFudF90eXBlIjoiYXV0aG9yaXphdGlvbl9jb2RlIiwic2NvcGUiOlsib3BlbmlkIiwiZnI6aWRtOioiXSwiYXV0aF90aW1lIjoxNzQzMTU4NjUyLCJyZWFsbSI6Ii8iLCJleHAiOjE3NDMxNjIyNTIsImlhdCI6MTc0MzE1ODY1MiwiZXhwaXJlc19pbiI6MzYwMCwianRpIjoidWd3UEFBU1RPelRyRXlTM3JYMjlUVEg0MGMwIn0.ksVgiQOwcpG733x7ekd0vXzS5KkcvPg7PB7kHgmlABau9IZQnIAwP0e0EAnMX-Y0wHt7WNb4nMD8Pgt5xaP5FDi0XjL4_Y5hrsiAXDp1eLqz_186pHES_JQNFxewqq6dh9c3gPMPNXRa-ZyPXoGV5Ck5jC-RIa_73Cl5fe6UTubNP_CXV759qO2j6bnwpIAKKfWkpJTdUdfxAfq9wK-sLCqC_QQkeTROx1EXJ-akxthBRPnqj4ICDQ1rXgekWinMvubi_mpCnvZsYTHA292gjNnD2sT5xaGp0OoyRUjvY-U_m7bubRMX8Be0txewRDzEyrD9rpRoutENZLO0FEEIQw host: openam-perf-200m.forgeblocks.com content-length: 265 body:StringChunksRequestBody{charset=UTF-8, content={ "_id" : "005cca7f-d2b8-402d-8c5e-034b34962279", "givenName" : "Yueh", "sn" : "Akbas", "mail" : "user.112612363@example.com", "telephoneNumber" : "774-816-546", "description" : "Updated Managed User", "userName" : "user.112612363" }} ========================= HTTP response: <<<<<<<<<<<<<<<<<<<<<<<<< [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 01:00 h [INFO] Finished at: 2025-03-28T10:45:51Z [INFO] ------------------------------------------------------------------------ 2025-03-28 10:45:51,368 INFO Gatling simulation run ended successfully 2025-03-28 10:45:51,368 DEBUG No archive name provided. No archive will be created ________________________________________________________________________________ [2025-03-28 10:46:16] update-1700 show_stderr : Display stderr order ________________________________________________________________________________ 2025-03-28 10:46:16,186 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_simple_managed_users/07_Scenario_scenario/05_update-1700/stderr.txt is small (0 lines), printing all lines: 2025-03-28 10:46:16,186 INFO ----- output ----- ________________________________________________________________________________ [2025-03-28 10:46:16] update-1700 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-03-28 10:46:16] update-1700 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2025-03-28 10:46:16] update-1700 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped