--Task-- name: login-tree-250 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 bellow loop: False interval: None dependencies: [] wait_for: ['login-tree-200'] preceding_task: login-tree-200 options: {'testCase': 'idc.loginProtect', 'testMode': 'open', 'throughput': 250, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login_Protect'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-23 07:53:51] login-tree-250 pre : N/A ________________________________________________________________________________ 2024-06-23 07:53:51 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-23 07:53:51 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-250/stdout.txt Pod log : /results/orders/login-tree-250 2024-06-23 07:53:51,305 INFO 2024-06-23 07:53:51,306 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 07:53:51,306 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:51,627 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:51,627 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:51,628 DEBUG --- stdout --- 2024-06-23 07:53:51,628 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:51,628 DEBUG --- stderr --- 2024-06-23 07:53:51,628 DEBUG ________________________________________________________________________________ [2024-06-23 07:53:51] login-tree-250 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-23 07:53:51,629 INFO 2024-06-23 07:53:51,629 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 07:53:51,629 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:51,962 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:51,962 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:51,962 DEBUG --- stdout --- 2024-06-23 07:53:51,962 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:51,962 DEBUG --- stderr --- 2024-06-23 07:53:51,962 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.loginProtect@ema-perf-p1" --results-folder /results/gatling/login-tree-250 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-23 07:53:51] login-tree-250 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-23 07:53:51,963 INFO 2024-06-23 07:53:51,963 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 07:53:51,963 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:52,284 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:52,284 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:52,284 DEBUG --- stdout --- 2024-06-23 07:53:52,284 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:52,284 DEBUG --- stderr --- 2024-06-23 07:53:52,284 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/order.json { "name": "login-tree-250", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.loginProtect@ema-perf-p1\" --results-folder /results/gatling/login-tree-250 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-250", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=69cee86761d9c02 -Dlogin_tree=Login_Protect -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.loginProtect -DtestMode=open -Dthroughput=250 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-23 07:53:52] login-tree-250 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-23 07:53:52,287 INFO 2024-06-23 07:53:52,287 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-23 07:53:52,287 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 07:53:52,542 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 07:53:52,542 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:52,542 DEBUG --- stdout --- 2024-06-23 07:53:52,542 DEBUG WAITING 2024-06-23 07:53:52,542 DEBUG --- stderr --- 2024-06-23 07:53:52,543 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 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-06-23 07:53:52] login-tree-250 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-23 07:53:52,543 INFO 2024-06-23 07:53:52,543 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 07:53:52,543 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:52,867 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:52,867 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:52,867 DEBUG --- stdout --- 2024-06-23 07:53:52,868 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:52,868 DEBUG --- stderr --- 2024-06-23 07:53:52,868 DEBUG 2024-06-23 07:53:52,868 INFO 2024-06-23 07:53:52,868 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-23 07:53:52,868 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 07:53:54,242 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:54,242 DEBUG --- stdout --- 2024-06-23 07:53:54,242 DEBUG 2024-06-23 07:53:54,242 DEBUG --- stderr --- 2024-06-23 07:53:54,242 DEBUG Order has been successfully forwarded to overseer 2024-06-23 07:53:54 - INFO: Check if Gatling simulation started successfully 2024-06-23 07:53:54,243 INFO 2024-06-23 07:53:54,243 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 07:53:54,243 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:54,568 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:54,568 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:54,568 DEBUG --- stdout --- 2024-06-23 07:53:54,568 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:54,569 DEBUG --- stderr --- 2024-06-23 07:53:54,569 DEBUG 2024-06-23 07:53:54,569 INFO 2024-06-23 07:53:54,569 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- head -n 25 /results/orders/login-tree-250/stdout.txt | grep "started..." 2024-06-23 07:53:54,569 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 07:53:55,316 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-23 07:54:01,074 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-23 07:54:01,074 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:54:01,074 DEBUG --- stdout --- 2024-06-23 07:54:01,074 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.4 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 07:54:01,074 DEBUG --- stderr --- 2024-06-23 07:54:01,074 DEBUG ________________________________________________________________________________ [2024-06-23 07:54:01] login-tree-250 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 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, 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 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-23 08:56:11] login-tree-250 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-23 08:56:11,119 INFO 2024-06-23 08:56:11,119 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:11,119 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:11,462 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:11,462 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:11,464 DEBUG --- stdout --- 2024-06-23 08:56:11,464 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:11,464 DEBUG --- stderr --- 2024-06-23 08:56:11,464 DEBUG 2024-06-23 08:56:11,465 INFO 2024-06-23 08:56:11,465 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- rm -f /results/orders/order.json 2024-06-23 08:56:11,465 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 08:56:12,258 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:12,258 DEBUG --- stdout --- 2024-06-23 08:56:12,258 DEBUG 2024-06-23 08:56:12,258 DEBUG --- stderr --- 2024-06-23 08:56:12,258 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-23 08:56:12] login-tree-250 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-23 08:56:12 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/order.json 2024-06-23 08:56:12,259 INFO 2024-06-23 08:56:12,259 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:12,259 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:12,586 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:12,587 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:12,587 DEBUG --- stdout --- 2024-06-23 08:56:12,587 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:12,587 DEBUG --- stderr --- 2024-06-23 08:56:12,587 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 08:56:12,587 INFO 2024-06-23 08:56:12,588 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:12,588 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:12,948 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:12,948 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:12,948 DEBUG --- stdout --- 2024-06-23 08:56:12,948 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:12,948 DEBUG --- stderr --- 2024-06-23 08:56:12,948 DEBUG 2024-06-23 08:56:12,948 INFO 2024-06-23 08:56:12,948 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-250/rc.txt" ]; then echo "/results/orders/login-tree-250/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-250/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/rc.txt file found" 2024-06-23 08:56:12,949 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 08:56:13,765 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 08:56:13,765 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:13,765 DEBUG --- stdout --- 2024-06-23 08:56:13,765 DEBUG /results/orders/login-tree-250/rc.txt file found 2024-06-23 08:56:13,765 DEBUG --- stderr --- 2024-06-23 08:56:13,765 DEBUG 2024-06-23 08:56:13,766 INFO 2024-06-23 08:56:13,766 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:13,766 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:14,082 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:14,083 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:14,083 DEBUG --- stdout --- 2024-06-23 08:56:14,083 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:14,083 DEBUG --- stderr --- 2024-06-23 08:56:14,083 DEBUG 2024-06-23 08:56:14,083 INFO 2024-06-23 08:56:14,083 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-250/rc.txt 2024-06-23 08:56:14,899 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 08:56:14,899 DEBUG --- stdout --- 2024-06-23 08:56:14,899 DEBUG 4 /results/orders/login-tree-250/rc.txt 2024-06-23 08:56:14,900 DEBUG --- stderr --- 2024-06-23 08:56:14,900 DEBUG 2024-06-23 08:56:14,900 INFO - Download file (size 4k) 2024-06-23 08:56:14,900 INFO 2024-06-23 08:56:14,900 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:14,900 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:15,220 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:15,220 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:15,220 DEBUG --- stdout --- 2024-06-23 08:56:15,220 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:15,220 DEBUG --- stderr --- 2024-06-23 08:56:15,220 DEBUG source : /results/orders/login-tree-250/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt 2024-06-23 08:56:15,221 INFO 2024-06-23 08:56:15,221 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:15,221 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:15,525 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:15,525 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:15,525 DEBUG --- stdout --- 2024-06-23 08:56:15,525 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:15,525 DEBUG --- stderr --- 2024-06-23 08:56:15,525 DEBUG 2024-06-23 08:56:15,526 INFO 2024-06-23 08:56:15,526 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-250/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt 2024-06-23 08:56:15,526 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 08:56:16,306 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:16,306 DEBUG --- stdout --- 2024-06-23 08:56:16,306 DEBUG tar: Removing leading `/' from member names 2024-06-23 08:56:16,306 DEBUG --- stderr --- 2024-06-23 08:56:16,306 DEBUG 2024-06-23 08:56:16,307 INFO 2024-06-23 08:56:16,307 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:16,307 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:16,627 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:16,628 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:16,629 DEBUG --- stdout --- 2024-06-23 08:56:16,629 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:16,629 DEBUG --- stderr --- 2024-06-23 08:56:16,629 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 08:56:16,629 INFO 2024-06-23 08:56:16,630 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:16,630 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:16,945 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:16,945 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:16,945 DEBUG --- stdout --- 2024-06-23 08:56:16,945 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:16,945 DEBUG --- stderr --- 2024-06-23 08:56:16,945 DEBUG 2024-06-23 08:56:16,946 INFO 2024-06-23 08:56:16,946 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-250/stderr.txt" ]; then echo "/results/orders/login-tree-250/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-250/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/stderr.txt file found" 2024-06-23 08:56:16,946 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 08:56:17,760 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 08:56:17,760 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:17,760 DEBUG --- stdout --- 2024-06-23 08:56:17,760 DEBUG /results/orders/login-tree-250/stderr.txt file found 2024-06-23 08:56:17,760 DEBUG --- stderr --- 2024-06-23 08:56:17,760 DEBUG 2024-06-23 08:56:17,760 INFO 2024-06-23 08:56:17,761 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:17,761 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:18,077 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:18,077 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:18,077 DEBUG --- stdout --- 2024-06-23 08:56:18,077 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:18,077 DEBUG --- stderr --- 2024-06-23 08:56:18,077 DEBUG 2024-06-23 08:56:18,077 INFO 2024-06-23 08:56:18,077 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-250/stderr.txt 2024-06-23 08:56:18,876 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 08:56:18,877 DEBUG --- stdout --- 2024-06-23 08:56:18,877 DEBUG 0 /results/orders/login-tree-250/stderr.txt 2024-06-23 08:56:18,877 DEBUG --- stderr --- 2024-06-23 08:56:18,877 DEBUG 2024-06-23 08:56:18,877 INFO - Download file (size 0k) 2024-06-23 08:56:18,877 INFO 2024-06-23 08:56:18,877 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:18,877 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:19,186 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:19,186 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:19,186 DEBUG --- stdout --- 2024-06-23 08:56:19,186 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:19,186 DEBUG --- stderr --- 2024-06-23 08:56:19,186 DEBUG source : /results/orders/login-tree-250/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt 2024-06-23 08:56:19,187 INFO 2024-06-23 08:56:19,187 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:19,187 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:19,556 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:19,556 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:19,556 DEBUG --- stdout --- 2024-06-23 08:56:19,556 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:19,556 DEBUG --- stderr --- 2024-06-23 08:56:19,556 DEBUG 2024-06-23 08:56:19,557 INFO 2024-06-23 08:56:19,557 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-250/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt 2024-06-23 08:56:19,557 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 08:56:20,311 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:20,311 DEBUG --- stdout --- 2024-06-23 08:56:20,312 DEBUG tar: Removing leading `/' from member names 2024-06-23 08:56:20,312 DEBUG --- stderr --- 2024-06-23 08:56:20,312 DEBUG 2024-06-23 08:56:20,312 INFO 2024-06-23 08:56:20,312 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:20,312 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:20,622 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:20,622 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:20,622 DEBUG --- stdout --- 2024-06-23 08:56:20,622 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:20,622 DEBUG --- stderr --- 2024-06-23 08:56:20,622 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 08:56:20,623 INFO 2024-06-23 08:56:20,623 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:20,623 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:20,929 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:20,929 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:20,929 DEBUG --- stdout --- 2024-06-23 08:56:20,929 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:20,929 DEBUG --- stderr --- 2024-06-23 08:56:20,929 DEBUG 2024-06-23 08:56:20,930 INFO 2024-06-23 08:56:20,930 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-250/stdout.txt" ]; then echo "/results/orders/login-tree-250/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-250/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/stdout.txt file found" 2024-06-23 08:56:20,930 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 08:56:21,732 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 08:56:21,732 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:21,733 DEBUG --- stdout --- 2024-06-23 08:56:21,733 DEBUG /results/orders/login-tree-250/stdout.txt file found 2024-06-23 08:56:21,734 DEBUG --- stderr --- 2024-06-23 08:56:21,734 DEBUG 2024-06-23 08:56:21,734 INFO 2024-06-23 08:56:21,734 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:21,734 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:22,058 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:22,058 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:22,058 DEBUG --- stdout --- 2024-06-23 08:56:22,058 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:22,058 DEBUG --- stderr --- 2024-06-23 08:56:22,058 DEBUG 2024-06-23 08:56:22,058 INFO 2024-06-23 08:56:22,059 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-250/stdout.txt 2024-06-23 08:56:22,916 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 08:56:22,917 DEBUG --- stdout --- 2024-06-23 08:56:22,917 DEBUG 2060 /results/orders/login-tree-250/stdout.txt 2024-06-23 08:56:22,917 DEBUG --- stderr --- 2024-06-23 08:56:22,917 DEBUG 2024-06-23 08:56:22,917 INFO - Download file (size 2060k) 2024-06-23 08:56:22,917 INFO 2024-06-23 08:56:22,917 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:22,917 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:23,257 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:23,257 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:23,257 DEBUG --- stdout --- 2024-06-23 08:56:23,257 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:23,258 DEBUG --- stderr --- 2024-06-23 08:56:23,258 DEBUG source : /results/orders/login-tree-250/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stdout.txt 2024-06-23 08:56:23,258 INFO 2024-06-23 08:56:23,258 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:23,258 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:23,572 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:23,572 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:23,572 DEBUG --- stdout --- 2024-06-23 08:56:23,572 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:23,572 DEBUG --- stderr --- 2024-06-23 08:56:23,572 DEBUG 2024-06-23 08:56:23,573 INFO 2024-06-23 08:56:23,573 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-250/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stdout.txt 2024-06-23 08:56:23,573 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 08:56:24,747 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:24,747 DEBUG --- stdout --- 2024-06-23 08:56:24,747 DEBUG tar: Removing leading `/' from member names 2024-06-23 08:56:24,747 DEBUG --- stderr --- 2024-06-23 08:56:24,747 DEBUG ________________________________________________________________________________ [2024-06-23 08:56:24] login-tree-250 show_cmd : Display order command ________________________________________________________________________________ 2024-06-23 08:56:24,748 INFO 2024-06-23 08:56:24,748 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:24,748 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:25,051 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:25,051 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:25,051 DEBUG --- stdout --- 2024-06-23 08:56:25,051 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:25,051 DEBUG --- stderr --- 2024-06-23 08:56:25,051 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 08:56:25,052 INFO 2024-06-23 08:56:25,052 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:25,052 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:25,361 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:25,361 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:25,361 DEBUG --- stdout --- 2024-06-23 08:56:25,361 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:25,361 DEBUG --- stderr --- 2024-06-23 08:56:25,361 DEBUG 2024-06-23 08:56:25,362 INFO 2024-06-23 08:56:25,362 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-250/cmd.txt" ]; then echo "/results/orders/login-tree-250/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-250/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/cmd.txt file found" 2024-06-23 08:56:25,362 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 08:56:26,181 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 08:56:26,181 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:26,181 DEBUG --- stdout --- 2024-06-23 08:56:26,181 DEBUG /results/orders/login-tree-250/cmd.txt file found 2024-06-23 08:56:26,181 DEBUG --- stderr --- 2024-06-23 08:56:26,181 DEBUG 2024-06-23 08:56:26,182 INFO 2024-06-23 08:56:26,182 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:26,182 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:26,509 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:26,510 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:26,510 DEBUG --- stdout --- 2024-06-23 08:56:26,510 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:26,510 DEBUG --- stderr --- 2024-06-23 08:56:26,510 DEBUG 2024-06-23 08:56:26,510 INFO 2024-06-23 08:56:26,510 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-250/cmd.txt 2024-06-23 08:56:27,330 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 08:56:27,331 DEBUG --- stdout --- 2024-06-23 08:56:27,332 DEBUG 4 /results/orders/login-tree-250/cmd.txt 2024-06-23 08:56:27,333 DEBUG --- stderr --- 2024-06-23 08:56:27,333 DEBUG 2024-06-23 08:56:27,333 INFO - Download file (size 4k) 2024-06-23 08:56:27,333 INFO 2024-06-23 08:56:27,333 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:27,334 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:27,651 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:27,651 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:27,651 DEBUG --- stdout --- 2024-06-23 08:56:27,651 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:27,651 DEBUG --- stderr --- 2024-06-23 08:56:27,651 DEBUG source : /results/orders/login-tree-250/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt 2024-06-23 08:56:27,652 INFO 2024-06-23 08:56:27,652 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 08:56:27,652 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 08:56:27,974 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 08:56:27,975 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:27,975 DEBUG --- stdout --- 2024-06-23 08:56:27,975 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 08:56:27,975 DEBUG --- stderr --- 2024-06-23 08:56:27,975 DEBUG 2024-06-23 08:56:27,975 INFO 2024-06-23 08:56:27,975 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-250/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt 2024-06-23 08:56:27,975 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 08:56:28,765 INFO [loop_until]: OK (rc = 0) 2024-06-23 08:56:28,766 DEBUG --- stdout --- 2024-06-23 08:56:28,766 DEBUG tar: Removing leading `/' from member names 2024-06-23 08:56:28,766 DEBUG --- stderr --- 2024-06-23 08:56:28,766 DEBUG 2024-06-23 08:56:28,766 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt is small (1 lines), printing all lines: 2024-06-23 08:56:28,766 DEBUG ----- output ----- 2024-06-23 08:56:28,766 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.loginProtect@ema-perf-p1" --results-folder /results/gatling/login-tree-250 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-23 08:56:28,766 INFO ________________________________________________________________________________ [2024-06-23 08:56:28] login-tree-250 show_rc : Display rc order ________________________________________________________________________________ 2024-06-23 08:56:28,767 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt is small (1 lines), printing all lines: 2024-06-23 08:56:28,767 DEBUG ----- output ----- 2024-06-23 08:56:28,767 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-23 08:56:28,767 INFO ________________________________________________________________________________ [2024-06-23 08:56:28] login-tree-250 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-23 08:56:28,771 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stdout.txt is 26030 lines): 2024-06-23 08:56:28,772 DEBUG ----- output ----- 2024-06-23 08:56:28,772 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-23 08:56:28,772 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-23 08:56:28,772 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 08:56:28,772 INFO 2024-06-23 08:56:28,772 INFO ================================================================================ 2024-06-23 08:56:28,772 INFO 2024-06-23 08:54:03 5s elapsed 2024-06-23 08:56:28,772 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 08:56:28,772 INFO > Global (OK=416 KO=0 ) 2024-06-23 08:56:28,772 INFO > protectLoginInitiate (OK=103 KO=0 ) 2024-06-23 08:56:28,772 INFO > PingOneProtectInitializeCallback (OK=101 KO=0 ) 2024-06-23 08:56:28,772 INFO > restLoginUsernamePasswordCallback (OK=99 KO=0 ) 2024-06-23 08:56:28,772 INFO > PingOneProtectEvaluationCallback (OK=88 KO=0 ) 2024-06-23 08:56:28,772 INFO > restloginProgressiveProfileCallback (OK=25 KO=0 ) 2024-06-23 08:56:28,772 INFO 2024-06-23 08:56:28,772 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 08:56:28,772 INFO [- ] 0% 2024-06-23 08:56:28,772 INFO waiting: 902372 / active: 18 / done: 87 2024-06-23 08:56:28,772 INFO ================================================================================ 2024-06-23 08:56:28,772 INFO 2024-06-23 08:56:28,772 INFO 2024-06-23 08:56:28,772 INFO ================================================================================ 2024-06-23 08:56:28,772 INFO 2024-06-23 08:54:08 10s elapsed 2024-06-23 08:56:28,772 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 08:56:28,772 INFO > Global (OK=1710 KO=0 ) 2024-06-23 08:56:28,772 INFO > protectLoginInitiate (OK=411 KO=0 ) 2024-06-23 08:56:28,772 INFO > PingOneProtectInitializeCallback (OK=410 KO=0 ) 2024-06-23 08:56:28,772 INFO > restLoginUsernamePasswordCallback (OK=406 KO=0 ) 2024-06-23 08:56:28,772 INFO > PingOneProtectEvaluationCallback (OK=385 KO=0 ) 2024-06-23 08:56:28,772 INFO > restloginProgressiveProfileCallback (OK=98 KO=0 ) 2024-06-23 08:56:28,773 INFO 2024-06-23 08:56:28,773 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 08:56:28,773 INFO [- ] 0% 2024-06-23 08:56:28,773 INFO waiting: 902059 / active: 37 / done: 381 2024-06-23 08:56:28,773 INFO ================================================================================ 2024-06-23 08:56:28,773 INFO 2024-06-23 08:56:28,773 INFO 2024-06-23 08:56:28,773 INFO ================================================================================ 2024-06-23 08:56:28,773 INFO 2024-06-23 08:54:13 15s elapsed 2024-06-23 08:56:28,773 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 08:56:28,773 INFO > Global (OK=3919 KO=0 ) 2024-06-23 08:56:28,773 INFO > protectLoginInitiate (OK=934 KO=0 ) 2024-06-23 08:56:28,773 INFO > PingOneProtectInitializeCallback (OK=928 KO=0 ) 2024-06-23 08:56:28,773 INFO > restLoginUsernamePasswordCallback (OK=921 KO=0 ) 2024-06-23 08:56:28,773 INFO > PingOneProtectEvaluationCallback (OK=890 KO=0 ) 2024-06-23 08:56:28,773 INFO > restloginProgressiveProfileCallback (OK=246 KO=0 ) 2024-06-23 08:56:28,773 INFO 2024-06-23 08:56:28,773 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 08:56:28,773 INFO [- ] 0% 2024-06-23 08:56:28,773 INFO waiting: 901538 / active: 53 / done: 886 2024-06-23 08:56:28,773 INFO ================================================================================ 2024-06-23 08:56:28,773 INFO 2024-06-23 08:56:28,773 INFO 2024-06-23 08:56:28,773 INFO ================================================================================ 2024-06-23 08:56:28,773 INFO 2024-06-23 08:54:18 20s elapsed 2024-06-23 08:56:28,773 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 08:56:28,773 INFO > Global (OK=6986 KO=0 ) 2024-06-23 08:56:28,773 INFO > protectLoginInitiate (OK=1661 KO=0 ) 2024-06-23 08:56:28,773 INFO > PingOneProtectInitializeCallback (OK=1653 KO=0 ) 2024-06-23 08:56:28,773 INFO > restLoginUsernamePasswordCallback (OK=1642 KO=0 ) 2024-06-23 08:56:28,773 INFO > PingOneProtectEvaluationCallback (OK=1598 KO=0 ) 2024-06-23 08:56:28,773 INFO > restloginProgressiveProfileCallback (OK=432 KO=0 ) 2024-06-23 08:56:28,773 INFO 2024-06-23 08:56:28,773 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 08:56:28,773 INFO [- ] 0% 2024-06-23 08:56:28,773 INFO waiting: 900808 / active: 80 / done: 1589 2024-06-23 08:56:28,773 INFO ================================================================================ 2024-06-23 08:56:28,773 INFO 2024-06-23 08:56:28,773 INFO 2024-06-23 08:56:28,773 INFO ================================================================================ 2024-06-23 08:56:28,773 INFO 2024-06-23 08:54:23 25s elapsed 2024-06-23 08:56:28,774 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 08:56:28,774 INFO > Global (OK=10963 KO=0 ) 2024-06-23 08:56:28,774 INFO > protectLoginInitiate (OK=2597 KO=0 ) 2024-06-23 08:56:28,774 INFO > PingOneProtectInitializeCallback (OK=2586 KO=0 ) 2024-06-23 08:56:28,774 INFO > restLoginUsernamePasswordCallback (OK=2575 KO=0 ) 2024-06-23 08:56:28,774 INFO > PingOneProtectEvaluationCallback (OK=2520 KO=0 ) 2024-06-23 08:56:28,774 INFO > restloginProgressiveProfileCallback (OK=685 KO=0 ) 2024-06-23 08:56:28,774 INFO 2024-06-23 08:56:28,774 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 08:56:28,774 INFO [- ] 0% 2024-06-23 08:56:28,774 INFO waiting: 899869 / active: 98 / done: 2510 2024-06-23 08:56:28,774 INFO ================================================================================ 2024-06-23 08:56:28,774 INFO 2024-06-23 08:56:28,774 INFO 2024-06-23 08:56:28,774 INFO ================================================================================ 2024-06-23 08:56:28,774 INFO 2024-06-23 08:54:28 30s elapsed 2024-06-23 08:56:28,774 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 08:56:28,774 INFO > Global (OK=15826 KO=0 ) 2024-06-23 08:56:28,774 INFO > protectLoginInitiate (OK=3740 KO=0 ) 2024-06-23 08:56:28,774 INFO > PingOneProtectInitializeCallback (OK=3728 KO=0 ) 2024-06-23 08:56:28,774 INFO > restLoginUsernamePasswordCallback (OK=3711 KO=0 ) 2024-06-23 08:56:28,774 INFO > PingOneProtectEvaluationCallback (OK=3636 KO=0 ) 2024-06-23 08:56:28,774 INFO > restloginProgressiveProfileCallback (OK=1011 KO=0 ) 2024-06-23 08:56:28,774 INFO 2024-06-23 08:56:28,774 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 08:56:28,774 INFO [- ] 0% 2024-06-23 08:56:28,774 INFO waiting: 898722 / active: 128 / done: 3627 2024-06-23 08:56:28,774 INFO ================================================================================ 2024-06-23 08:56:28,774 INFO 2024-06-23 08:56:28,774 INFO 2024-06-23 08:56:28,774 INFO [...] 2024-06-23 08:56:28,782 INFO > status.find.is(200), but actually found 401 44 (36.67%) 2024-06-23 08:56:28,782 INFO > status.find.is(200), but actually found 502 12 (10.00%) 2024-06-23 08:56:28,782 INFO > PingOneProtectInitializeCallback: Failed to build request: No 4 ( 3.33%) 2024-06-23 08:56:28,782 INFO attribute named 'callbacks' is defined 2024-06-23 08:56:28,782 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 4 ( 3.33%) 2024-06-23 08:56:28,782 INFO attribute named 'callbacks' is defined 2024-06-23 08:56:28,782 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 4 ( 3.33%) 2024-06-23 08:56:28,782 INFO attribute named 'callbacks' is defined 2024-06-23 08:56:28,782 INFO > restloginProgressiveProfileCallback: Failed to build request: 4 ( 3.33%) 2024-06-23 08:56:28,782 INFO No attribute named 'callbacks' is defined 2024-06-23 08:56:28,782 INFO > Request timeout to openam-ema-perf-p1.forgeblocks.com/35.241.5 2 ( 1.67%) 2024-06-23 08:56:28,782 INFO 9.5:443 after 60000 ms 2024-06-23 08:56:28,782 INFO > j.i.IOException: Premature close 1 ( 0.83%) 2024-06-23 08:56:28,782 INFO 2024-06-23 08:56:28,782 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 08:56:28,782 INFO [#########################################################################-] 99% 2024-06-23 08:56:28,782 INFO waiting: 1266 / active: 159 / done: 901052 2024-06-23 08:56:28,782 INFO ================================================================================ 2024-06-23 08:56:28,782 INFO 2024-06-23 08:56:28,782 INFO 2024-06-23 08:56:28,782 INFO ================================================================================ 2024-06-23 08:56:28,782 INFO 2024-06-23 09:54:28 3630s elapsed 2024-06-23 08:56:28,782 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 08:56:28,782 INFO > Global (OK=3849627 KO=104 ) 2024-06-23 08:56:28,782 INFO > protectLoginInitiate (OK=902458 KO=4 ) 2024-06-23 08:56:28,783 INFO > PingOneProtectInitializeCallback (OK=902434 KO=6 ) 2024-06-23 08:56:28,783 INFO > restLoginUsernamePasswordCallback (OK=902394 KO=6 ) 2024-06-23 08:56:28,783 INFO > PingOneProtectEvaluationCallback (OK=902239 KO=41 ) 2024-06-23 08:56:28,783 INFO > restloginProgressiveProfileCallback (OK=240102 KO=47 ) 2024-06-23 08:56:28,783 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 08:56:28,783 INFO > jsonPath($.tokenId).find.exists, found nothing 45 (37.50%) 2024-06-23 08:56:28,783 INFO > status.find.is(200), but actually found 401 44 (36.67%) 2024-06-23 08:56:28,783 INFO > status.find.is(200), but actually found 502 12 (10.00%) 2024-06-23 08:56:28,783 INFO > PingOneProtectInitializeCallback: Failed to build request: No 4 ( 3.33%) 2024-06-23 08:56:28,783 INFO attribute named 'callbacks' is defined 2024-06-23 08:56:28,783 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 4 ( 3.33%) 2024-06-23 08:56:28,783 INFO attribute named 'callbacks' is defined 2024-06-23 08:56:28,783 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 4 ( 3.33%) 2024-06-23 08:56:28,783 INFO attribute named 'callbacks' is defined 2024-06-23 08:56:28,783 INFO > restloginProgressiveProfileCallback: Failed to build request: 4 ( 3.33%) 2024-06-23 08:56:28,783 INFO No attribute named 'callbacks' is defined 2024-06-23 08:56:28,783 INFO > Request timeout to openam-ema-perf-p1.forgeblocks.com/35.241.5 2 ( 1.67%) 2024-06-23 08:56:28,783 INFO 9.5:443 after 60000 ms 2024-06-23 08:56:28,783 INFO > j.i.IOException: Premature close 1 ( 0.83%) 2024-06-23 08:56:28,783 INFO 2024-06-23 08:56:28,783 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 08:56:28,783 INFO [#########################################################################-] 99% 2024-06-23 08:56:28,783 INFO waiting: 0 / active: 259 / done: 902218 2024-06-23 08:56:28,783 INFO ================================================================================ 2024-06-23 08:56:28,783 INFO 2024-06-23 08:56:28,783 INFO 2024-06-23 08:56:28,783 INFO ================================================================================ 2024-06-23 08:56:28,783 INFO 2024-06-23 09:54:29 3630s elapsed 2024-06-23 08:56:28,783 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 08:56:28,783 INFO > Global (OK=3850051 KO=104 ) 2024-06-23 08:56:28,783 INFO > protectLoginInitiate (OK=902473 KO=4 ) 2024-06-23 08:56:28,783 INFO > PingOneProtectInitializeCallback (OK=902467 KO=6 ) 2024-06-23 08:56:28,783 INFO > restLoginUsernamePasswordCallback (OK=902467 KO=6 ) 2024-06-23 08:56:28,783 INFO > PingOneProtectEvaluationCallback (OK=902432 KO=41 ) 2024-06-23 08:56:28,783 INFO > restloginProgressiveProfileCallback (OK=240212 KO=47 ) 2024-06-23 08:56:28,783 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 08:56:28,783 INFO > jsonPath($.tokenId).find.exists, found nothing 45 (37.50%) 2024-06-23 08:56:28,783 INFO > status.find.is(200), but actually found 401 44 (36.67%) 2024-06-23 08:56:28,783 INFO > status.find.is(200), but actually found 502 12 (10.00%) 2024-06-23 08:56:28,783 INFO > PingOneProtectInitializeCallback: Failed to build request: No 4 ( 3.33%) 2024-06-23 08:56:28,784 INFO attribute named 'callbacks' is defined 2024-06-23 08:56:28,784 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 4 ( 3.33%) 2024-06-23 08:56:28,784 INFO attribute named 'callbacks' is defined 2024-06-23 08:56:28,784 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 4 ( 3.33%) 2024-06-23 08:56:28,784 INFO attribute named 'callbacks' is defined 2024-06-23 08:56:28,784 INFO > restloginProgressiveProfileCallback: Failed to build request: 4 ( 3.33%) 2024-06-23 08:56:28,784 INFO No attribute named 'callbacks' is defined 2024-06-23 08:56:28,784 INFO > Request timeout to openam-ema-perf-p1.forgeblocks.com/35.241.5 2 ( 1.67%) 2024-06-23 08:56:28,784 INFO 9.5:443 after 60000 ms 2024-06-23 08:56:28,784 INFO > j.i.IOException: Premature close 1 ( 0.83%) 2024-06-23 08:56:28,784 INFO 2024-06-23 08:56:28,784 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 08:56:28,784 INFO [##########################################################################]100% 2024-06-23 08:56:28,784 INFO waiting: 0 / active: 0 / done: 902477 2024-06-23 08:56:28,784 INFO ================================================================================ 2024-06-23 08:56:28,784 INFO 2024-06-23 08:56:28,784 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-23 08:56:28,784 INFO 2024-06-23 08:56:28,784 INFO ---------------------- 2024-06-23 08:56:28,784 INFO [23/Jun/2024 09:54:29] Create tar file login-tree-250.tar.gz 2024-06-23 08:56:28,784 INFO ---------------------- 2024-06-23 08:56:28,784 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-23 08:56:28,784 INFO RESULT_FOLDER=login-tree-250 2024-06-23 08:56:28,784 INFO SIMULATION_PATH=login-tree-250/loadtest-20240623075358196 2024-06-23 08:56:28,784 INFO PWD=/results/gatling/login-tree-250/loadtest-20240623075358196 2024-06-23 08:56:28,784 INFO [cmd] tar -czvf /results/gatling//login-tree-250.tar.gz --exclude=simulation.log * 2024-06-23 08:56:28,784 INFO /results/gatling 2024-06-23 08:56:28,784 INFO 2024-06-23 08:56:28,784 INFO ---------------------- 2024-06-23 08:56:28,784 INFO [23/Jun/2024 09:54:29] Update /results/gatling//latest 2024-06-23 08:56:28,784 INFO ---------------------- 2024-06-23 08:56:28,784 INFO 2024-06-23 08:56:28,784 INFO ---------------------- 2024-06-23 08:56:28,784 INFO [23/Jun/2024 09:54:29] Update /results/gatling//latest.tar.gz 2024-06-23 08:56:28,784 INFO ---------------------- 2024-06-23 08:56:28,784 INFO ________________________________________________________________________________ [2024-06-23 08:56:28] login-tree-250 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-23 08:56:28,785 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt is small (0 lines), printing all lines: 2024-06-23 08:56:28,785 DEBUG ----- output ----- 2024-06-23 08:56:28,785 DEBUG 2024-06-23 08:56:28,785 INFO ________________________________________________________________________________ [2024-06-23 08:56:28] login-tree-250 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-06-23 08:56:28] login-tree-250 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-23 08:56:28] login-tree-250 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped