--Task-- name: login-tree-150 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-100'] preceding_task: login-tree-100 options: {'testCase': 'idc.loginProtect', 'testMode': 'open', 'throughput': 150, '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-13 02:57:54] login-tree-150 pre : N/A ________________________________________________________________________________ 2024-06-13 02:57:54 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-13 02:57:54 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-150/stdout.txt Pod log : /results/orders/login-tree-150 2024-06-13 02:57:54,506 INFO 2024-06-13 02:57:54,507 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-13 02:57:54,507 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:54,812 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:54,812 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:54,812 DEBUG --- stdout --- 2024-06-13 02:57:54,812 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:54,812 DEBUG --- stderr --- 2024-06-13 02:57:54,812 DEBUG ________________________________________________________________________________ [2024-06-13 02:57:54] login-tree-150 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-13 02:57:54,813 INFO 2024-06-13 02:57:54,813 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-13 02:57:54,813 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:55,113 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:55,113 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:55,113 DEBUG --- stdout --- 2024-06-13 02:57:55,113 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:55,113 DEBUG --- stderr --- 2024-06-13 02:57:55,113 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-p1protect" --results-folder /results/gatling/login-tree-150 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-13 02:57:55] login-tree-150 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-13 02:57:55,114 INFO 2024-06-13 02:57:55,114 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-13 02:57:55,114 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:55,411 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:55,411 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:55,411 DEBUG --- stdout --- 2024-06-13 02:57:55,411 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:55,411 DEBUG --- stderr --- 2024-06-13 02:57:55,411 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/order.json { "name": "login-tree-150", "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-p1protect\" --results-folder /results/gatling/login-tree-150 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-150", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1protect.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=956c97cd5742324 -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=150 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-13 02:57:55] login-tree-150 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-13 02:57:55,413 INFO 2024-06-13 02:57:55,413 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-13 02:57:55,413 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-13 02:57:55,692 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 02:57:55,692 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:55,692 DEBUG --- stdout --- 2024-06-13 02:57:55,692 DEBUG WAITING 2024-06-13 02:57:55,693 DEBUG --- stderr --- 2024-06-13 02:57:55,693 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 27 0 --:--:-- --:--:-- --:--:-- 27 ________________________________________________________________________________ [2024-06-13 02:57:55] login-tree-150 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-13 02:57:55,693 INFO 2024-06-13 02:57:55,693 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-13 02:57:55,693 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:55,994 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:55,994 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:55,994 DEBUG --- stdout --- 2024-06-13 02:57:55,994 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:55,994 DEBUG --- stderr --- 2024-06-13 02:57:55,994 DEBUG 2024-06-13 02:57:55,994 INFO 2024-06-13 02:57:55,994 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/04_login-tree-150/order.json overseer-0-6c7d65494f-sln6z:/results/orders/order.json 2024-06-13 02:57:55,995 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 02:57:57,342 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:57,342 DEBUG --- stdout --- 2024-06-13 02:57:57,343 DEBUG 2024-06-13 02:57:57,343 DEBUG --- stderr --- 2024-06-13 02:57:57,343 DEBUG Order has been successfully forwarded to overseer 2024-06-13 02:57:57 - INFO: Check if Gatling simulation started successfully 2024-06-13 02:57:57,343 INFO 2024-06-13 02:57:57,343 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-13 02:57:57,343 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:57,653 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:57,653 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:57,653 DEBUG --- stdout --- 2024-06-13 02:57:57,653 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:57,653 DEBUG --- stderr --- 2024-06-13 02:57:57,653 DEBUG 2024-06-13 02:57:57,654 INFO 2024-06-13 02:57:57,654 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- head -n 25 /results/orders/login-tree-150/stdout.txt | grep "started..." 2024-06-13 02:57:57,654 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-13 02:57:58,384 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-13 02:58:04,115 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-13 02:58:04,115 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:58:04,115 DEBUG --- stdout --- 2024-06-13 02:58:04,115 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.3 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-13 02:58:04,115 DEBUG --- stderr --- 2024-06-13 02:58:04,115 DEBUG ________________________________________________________________________________ [2024-06-13 02:58:04] login-tree-150 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 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 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 07s, retry Order is running since 48m 07s, retry Order is running since 50m 07s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 08s, 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-13 04:00:13] login-tree-150 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-13 04:00:13,877 INFO 2024-06-13 04:00:13,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-13 04:00:13,877 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:14,224 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:14,224 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:14,224 DEBUG --- stdout --- 2024-06-13 04:00:14,224 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:14,224 DEBUG --- stderr --- 2024-06-13 04:00:14,224 DEBUG 2024-06-13 04:00:14,225 INFO 2024-06-13 04:00:14,225 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- rm -f /results/orders/order.json 2024-06-13 04:00:14,225 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 04:00:15,008 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:15,008 DEBUG --- stdout --- 2024-06-13 04:00:15,008 DEBUG 2024-06-13 04:00:15,008 DEBUG --- stderr --- 2024-06-13 04:00:15,008 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-13 04:00:15] login-tree-150 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-13 04:00:15 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/order.json 2024-06-13 04:00:15,009 INFO 2024-06-13 04:00:15,009 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-13 04:00:15,009 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:15,316 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:15,316 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:15,316 DEBUG --- stdout --- 2024-06-13 04:00:15,316 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:15,316 DEBUG --- stderr --- 2024-06-13 04:00:15,317 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 04:00:15,317 INFO 2024-06-13 04:00:15,317 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-13 04:00:15,317 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:15,616 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:15,617 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:15,617 DEBUG --- stdout --- 2024-06-13 04:00:15,617 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:15,617 DEBUG --- stderr --- 2024-06-13 04:00:15,617 DEBUG 2024-06-13 04:00:15,617 INFO 2024-06-13 04:00:15,617 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/rc.txt" ]; then echo "/results/orders/login-tree-150/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-150/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/rc.txt file found" 2024-06-13 04:00:15,618 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 04:00:16,425 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 04:00:16,426 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:16,426 DEBUG --- stdout --- 2024-06-13 04:00:16,426 DEBUG /results/orders/login-tree-150/rc.txt file found 2024-06-13 04:00:16,426 DEBUG --- stderr --- 2024-06-13 04:00:16,426 DEBUG 2024-06-13 04:00:16,426 INFO 2024-06-13 04:00:16,426 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-13 04:00:16,426 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:16,745 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:16,746 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:16,746 DEBUG --- stdout --- 2024-06-13 04:00:16,746 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:16,746 DEBUG --- stderr --- 2024-06-13 04:00:16,746 DEBUG 2024-06-13 04:00:16,746 INFO 2024-06-13 04:00:16,746 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-150/rc.txt 2024-06-13 04:00:17,557 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 04:00:17,558 DEBUG --- stdout --- 2024-06-13 04:00:17,558 DEBUG 4 /results/orders/login-tree-150/rc.txt 2024-06-13 04:00:17,558 DEBUG --- stderr --- 2024-06-13 04:00:17,558 DEBUG 2024-06-13 04:00:17,558 INFO - Download file (size 4k) 2024-06-13 04:00:17,558 INFO 2024-06-13 04:00:17,558 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-13 04:00:17,558 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:17,868 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:17,869 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:17,869 DEBUG --- stdout --- 2024-06-13 04:00:17,869 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:17,869 DEBUG --- stderr --- 2024-06-13 04:00:17,869 DEBUG source : /results/orders/login-tree-150/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/rc.txt 2024-06-13 04:00:17,869 INFO 2024-06-13 04:00:17,869 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-13 04:00:17,869 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:18,181 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:18,182 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:18,182 DEBUG --- stdout --- 2024-06-13 04:00:18,182 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:18,182 DEBUG --- stderr --- 2024-06-13 04:00:18,182 DEBUG 2024-06-13 04:00:18,182 INFO 2024-06-13 04:00:18,182 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-150/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/rc.txt 2024-06-13 04:00:18,182 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 04:00:18,919 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:18,919 DEBUG --- stdout --- 2024-06-13 04:00:18,919 DEBUG tar: Removing leading `/' from member names 2024-06-13 04:00:18,919 DEBUG --- stderr --- 2024-06-13 04:00:18,919 DEBUG 2024-06-13 04:00:18,920 INFO 2024-06-13 04:00:18,920 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-13 04:00:18,920 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:19,217 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:19,217 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:19,217 DEBUG --- stdout --- 2024-06-13 04:00:19,217 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:19,217 DEBUG --- stderr --- 2024-06-13 04:00:19,217 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 04:00:19,218 INFO 2024-06-13 04:00:19,218 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-13 04:00:19,218 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:19,517 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:19,517 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:19,517 DEBUG --- stdout --- 2024-06-13 04:00:19,517 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:19,518 DEBUG --- stderr --- 2024-06-13 04:00:19,518 DEBUG 2024-06-13 04:00:19,518 INFO 2024-06-13 04:00:19,518 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/stderr.txt" ]; then echo "/results/orders/login-tree-150/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-150/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/stderr.txt file found" 2024-06-13 04:00:19,518 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 04:00:20,319 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 04:00:20,319 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:20,319 DEBUG --- stdout --- 2024-06-13 04:00:20,319 DEBUG /results/orders/login-tree-150/stderr.txt file found 2024-06-13 04:00:20,319 DEBUG --- stderr --- 2024-06-13 04:00:20,319 DEBUG 2024-06-13 04:00:20,319 INFO 2024-06-13 04:00:20,319 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-13 04:00:20,319 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:20,617 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:20,617 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:20,617 DEBUG --- stdout --- 2024-06-13 04:00:20,617 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:20,617 DEBUG --- stderr --- 2024-06-13 04:00:20,617 DEBUG 2024-06-13 04:00:20,617 INFO 2024-06-13 04:00:20,618 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-150/stderr.txt 2024-06-13 04:00:21,416 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 04:00:21,417 DEBUG --- stdout --- 2024-06-13 04:00:21,417 DEBUG 0 /results/orders/login-tree-150/stderr.txt 2024-06-13 04:00:21,417 DEBUG --- stderr --- 2024-06-13 04:00:21,417 DEBUG 2024-06-13 04:00:21,417 INFO - Download file (size 0k) 2024-06-13 04:00:21,417 INFO 2024-06-13 04:00:21,417 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-13 04:00:21,417 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:21,727 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:21,727 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:21,727 DEBUG --- stdout --- 2024-06-13 04:00:21,727 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:21,727 DEBUG --- stderr --- 2024-06-13 04:00:21,727 DEBUG source : /results/orders/login-tree-150/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/stderr.txt 2024-06-13 04:00:21,728 INFO 2024-06-13 04:00:21,728 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-13 04:00:21,728 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:22,041 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:22,041 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:22,041 DEBUG --- stdout --- 2024-06-13 04:00:22,042 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:22,042 DEBUG --- stderr --- 2024-06-13 04:00:22,042 DEBUG 2024-06-13 04:00:22,042 INFO 2024-06-13 04:00:22,042 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-150/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/stderr.txt 2024-06-13 04:00:22,042 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 04:00:22,888 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:22,888 DEBUG --- stdout --- 2024-06-13 04:00:22,888 DEBUG tar: Removing leading `/' from member names 2024-06-13 04:00:22,888 DEBUG --- stderr --- 2024-06-13 04:00:22,888 DEBUG 2024-06-13 04:00:22,889 INFO 2024-06-13 04:00:22,889 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-13 04:00:22,889 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:23,189 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:23,189 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:23,190 DEBUG --- stdout --- 2024-06-13 04:00:23,190 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:23,190 DEBUG --- stderr --- 2024-06-13 04:00:23,190 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 04:00:23,190 INFO 2024-06-13 04:00:23,190 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-13 04:00:23,190 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:23,499 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:23,499 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:23,499 DEBUG --- stdout --- 2024-06-13 04:00:23,499 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:23,500 DEBUG --- stderr --- 2024-06-13 04:00:23,500 DEBUG 2024-06-13 04:00:23,500 INFO 2024-06-13 04:00:23,500 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/stdout.txt" ]; then echo "/results/orders/login-tree-150/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-150/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/stdout.txt file found" 2024-06-13 04:00:23,500 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 04:00:24,319 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 04:00:24,319 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:24,319 DEBUG --- stdout --- 2024-06-13 04:00:24,319 DEBUG /results/orders/login-tree-150/stdout.txt file found 2024-06-13 04:00:24,319 DEBUG --- stderr --- 2024-06-13 04:00:24,319 DEBUG 2024-06-13 04:00:24,320 INFO 2024-06-13 04:00:24,320 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-13 04:00:24,320 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:24,625 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:24,626 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:24,626 DEBUG --- stdout --- 2024-06-13 04:00:24,626 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:24,626 DEBUG --- stderr --- 2024-06-13 04:00:24,626 DEBUG 2024-06-13 04:00:24,626 INFO 2024-06-13 04:00:24,626 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-150/stdout.txt 2024-06-13 04:00:25,425 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 04:00:25,425 DEBUG --- stdout --- 2024-06-13 04:00:25,426 DEBUG 1416 /results/orders/login-tree-150/stdout.txt 2024-06-13 04:00:25,426 DEBUG --- stderr --- 2024-06-13 04:00:25,426 DEBUG 2024-06-13 04:00:25,426 INFO - Download file (size 1416k) 2024-06-13 04:00:25,426 INFO 2024-06-13 04:00:25,426 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-13 04:00:25,426 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:25,726 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:25,726 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:25,726 DEBUG --- stdout --- 2024-06-13 04:00:25,726 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:25,726 DEBUG --- stderr --- 2024-06-13 04:00:25,726 DEBUG source : /results/orders/login-tree-150/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/stdout.txt 2024-06-13 04:00:25,727 INFO 2024-06-13 04:00:25,727 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-13 04:00:25,727 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:26,032 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:26,032 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:26,032 DEBUG --- stdout --- 2024-06-13 04:00:26,032 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:26,032 DEBUG --- stderr --- 2024-06-13 04:00:26,032 DEBUG 2024-06-13 04:00:26,033 INFO 2024-06-13 04:00:26,033 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-150/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/stdout.txt 2024-06-13 04:00:26,033 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 04:00:27,165 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:27,166 DEBUG --- stdout --- 2024-06-13 04:00:27,166 DEBUG tar: Removing leading `/' from member names 2024-06-13 04:00:27,166 DEBUG --- stderr --- 2024-06-13 04:00:27,166 DEBUG ________________________________________________________________________________ [2024-06-13 04:00:27] login-tree-150 show_cmd : Display order command ________________________________________________________________________________ 2024-06-13 04:00:27,167 INFO 2024-06-13 04:00:27,167 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-13 04:00:27,167 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:27,508 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:27,508 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:27,508 DEBUG --- stdout --- 2024-06-13 04:00:27,508 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:27,508 DEBUG --- stderr --- 2024-06-13 04:00:27,508 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 04:00:27,508 INFO 2024-06-13 04:00:27,508 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-13 04:00:27,508 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:27,817 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:27,817 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:27,817 DEBUG --- stdout --- 2024-06-13 04:00:27,817 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:27,817 DEBUG --- stderr --- 2024-06-13 04:00:27,817 DEBUG 2024-06-13 04:00:27,818 INFO 2024-06-13 04:00:27,818 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/cmd.txt" ]; then echo "/results/orders/login-tree-150/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-150/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/cmd.txt file found" 2024-06-13 04:00:27,818 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 04:00:28,632 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 04:00:28,632 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:28,632 DEBUG --- stdout --- 2024-06-13 04:00:28,632 DEBUG /results/orders/login-tree-150/cmd.txt file found 2024-06-13 04:00:28,632 DEBUG --- stderr --- 2024-06-13 04:00:28,632 DEBUG 2024-06-13 04:00:28,633 INFO 2024-06-13 04:00:28,633 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-13 04:00:28,633 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:28,935 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:28,935 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:28,935 DEBUG --- stdout --- 2024-06-13 04:00:28,936 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:28,936 DEBUG --- stderr --- 2024-06-13 04:00:28,936 DEBUG 2024-06-13 04:00:28,936 INFO 2024-06-13 04:00:28,936 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-150/cmd.txt 2024-06-13 04:00:29,737 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 04:00:29,738 DEBUG --- stdout --- 2024-06-13 04:00:29,738 DEBUG 4 /results/orders/login-tree-150/cmd.txt 2024-06-13 04:00:29,738 DEBUG --- stderr --- 2024-06-13 04:00:29,738 DEBUG 2024-06-13 04:00:29,738 INFO - Download file (size 4k) 2024-06-13 04:00:29,739 INFO 2024-06-13 04:00:29,739 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-13 04:00:29,739 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:30,050 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:30,050 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:30,050 DEBUG --- stdout --- 2024-06-13 04:00:30,050 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:30,050 DEBUG --- stderr --- 2024-06-13 04:00:30,050 DEBUG source : /results/orders/login-tree-150/cmd.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/cmd.txt 2024-06-13 04:00:30,051 INFO 2024-06-13 04:00:30,051 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-13 04:00:30,051 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:30,359 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:30,359 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:30,359 DEBUG --- stdout --- 2024-06-13 04:00:30,359 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:30,359 DEBUG --- stderr --- 2024-06-13 04:00:30,359 DEBUG 2024-06-13 04:00:30,360 INFO 2024-06-13 04:00:30,360 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-150/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/cmd.txt 2024-06-13 04:00:30,360 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 04:00:31,101 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:31,102 DEBUG --- stdout --- 2024-06-13 04:00:31,102 DEBUG tar: Removing leading `/' from member names 2024-06-13 04:00:31,102 DEBUG --- stderr --- 2024-06-13 04:00:31,102 DEBUG 2024-06-13 04:00:31,102 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/cmd.txt is small (1 lines), printing all lines: 2024-06-13 04:00:31,102 DEBUG ----- output ----- 2024-06-13 04:00:31,102 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-p1protect" --results-folder /results/gatling/login-tree-150 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-13 04:00:31,102 INFO ________________________________________________________________________________ [2024-06-13 04:00:31] login-tree-150 show_rc : Display rc order ________________________________________________________________________________ 2024-06-13 04:00:31,103 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/rc.txt is small (1 lines), printing all lines: 2024-06-13 04:00:31,103 DEBUG ----- output ----- 2024-06-13 04:00:31,103 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-13 04:00:31,103 INFO ________________________________________________________________________________ [2024-06-13 04:00:31] login-tree-150 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-13 04:00:31,106 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/04_login-tree-150/stdout.txt is 19874 lines): 2024-06-13 04:00:31,106 DEBUG ----- output ----- 2024-06-13 04:00:31,106 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-13 04:00:31,106 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-13 04:00:31,106 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-13 04:00:31,106 INFO 2024-06-13 04:00:31,106 INFO ================================================================================ 2024-06-13 04:00:31,106 INFO 2024-06-13 03:58:07 5s elapsed 2024-06-13 04:00:31,106 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 04:00:31,106 INFO > Global (OK=216 KO=0 ) 2024-06-13 04:00:31,106 INFO > protectLoginInitiate (OK=60 KO=0 ) 2024-06-13 04:00:31,106 INFO > PingOneProtectInitializeCallback (OK=58 KO=0 ) 2024-06-13 04:00:31,106 INFO > restLoginUsernamePasswordCallback (OK=54 KO=0 ) 2024-06-13 04:00:31,106 INFO > PingOneProtectEvaluationCallback (OK=40 KO=0 ) 2024-06-13 04:00:31,107 INFO > restloginProgressiveProfileCallback (OK=4 KO=0 ) 2024-06-13 04:00:31,107 INFO 2024-06-13 04:00:31,107 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 04:00:31,107 INFO [- ] 0% 2024-06-13 04:00:31,107 INFO waiting: 541663 / active: 23 / done: 40 2024-06-13 04:00:31,107 INFO ================================================================================ 2024-06-13 04:00:31,107 INFO 2024-06-13 04:00:31,107 INFO 2024-06-13 04:00:31,107 INFO ================================================================================ 2024-06-13 04:00:31,107 INFO 2024-06-13 03:58:12 10s elapsed 2024-06-13 04:00:31,107 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 04:00:31,107 INFO > Global (OK=956 KO=0 ) 2024-06-13 04:00:31,107 INFO > protectLoginInitiate (OK=246 KO=0 ) 2024-06-13 04:00:31,107 INFO > PingOneProtectInitializeCallback (OK=241 KO=0 ) 2024-06-13 04:00:31,107 INFO > restLoginUsernamePasswordCallback (OK=234 KO=0 ) 2024-06-13 04:00:31,107 INFO > PingOneProtectEvaluationCallback (OK=209 KO=0 ) 2024-06-13 04:00:31,107 INFO > restloginProgressiveProfileCallback (OK=26 KO=0 ) 2024-06-13 04:00:31,107 INFO 2024-06-13 04:00:31,107 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 04:00:31,107 INFO [- ] 0% 2024-06-13 04:00:31,107 INFO waiting: 541475 / active: 44 / done: 207 2024-06-13 04:00:31,107 INFO ================================================================================ 2024-06-13 04:00:31,107 INFO 2024-06-13 04:00:31,107 INFO 2024-06-13 04:00:31,107 INFO ================================================================================ 2024-06-13 04:00:31,107 INFO 2024-06-13 03:58:17 15s elapsed 2024-06-13 04:00:31,107 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 04:00:31,107 INFO > Global (OK=2195 KO=0 ) 2024-06-13 04:00:31,107 INFO > protectLoginInitiate (OK=556 KO=0 ) 2024-06-13 04:00:31,107 INFO > PingOneProtectInitializeCallback (OK=548 KO=0 ) 2024-06-13 04:00:31,107 INFO > restLoginUsernamePasswordCallback (OK=538 KO=0 ) 2024-06-13 04:00:31,107 INFO > PingOneProtectEvaluationCallback (OK=501 KO=0 ) 2024-06-13 04:00:31,107 INFO > restloginProgressiveProfileCallback (OK=52 KO=0 ) 2024-06-13 04:00:31,107 INFO 2024-06-13 04:00:31,107 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 04:00:31,107 INFO [- ] 0% 2024-06-13 04:00:31,107 INFO waiting: 541162 / active: 64 / done: 500 2024-06-13 04:00:31,107 INFO ================================================================================ 2024-06-13 04:00:31,107 INFO 2024-06-13 04:00:31,108 INFO 2024-06-13 04:00:31,108 INFO ================================================================================ 2024-06-13 04:00:31,108 INFO 2024-06-13 03:58:22 20s elapsed 2024-06-13 04:00:31,108 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 04:00:31,108 INFO > Global (OK=3953 KO=0 ) 2024-06-13 04:00:31,108 INFO > protectLoginInitiate (OK=991 KO=0 ) 2024-06-13 04:00:31,108 INFO > PingOneProtectInitializeCallback (OK=981 KO=0 ) 2024-06-13 04:00:31,108 INFO > restLoginUsernamePasswordCallback (OK=967 KO=0 ) 2024-06-13 04:00:31,108 INFO > PingOneProtectEvaluationCallback (OK=922 KO=0 ) 2024-06-13 04:00:31,108 INFO > restloginProgressiveProfileCallback (OK=92 KO=0 ) 2024-06-13 04:00:31,108 INFO 2024-06-13 04:00:31,108 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 04:00:31,108 INFO [- ] 0% 2024-06-13 04:00:31,108 INFO waiting: 540724 / active: 83 / done: 919 2024-06-13 04:00:31,108 INFO ================================================================================ 2024-06-13 04:00:31,108 INFO 2024-06-13 04:00:31,108 INFO 2024-06-13 04:00:31,108 INFO ================================================================================ 2024-06-13 04:00:31,108 INFO 2024-06-13 03:58:27 25s elapsed 2024-06-13 04:00:31,108 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 04:00:31,108 INFO > Global (OK=6218 KO=0 ) 2024-06-13 04:00:31,108 INFO > protectLoginInitiate (OK=1551 KO=0 ) 2024-06-13 04:00:31,108 INFO > PingOneProtectInitializeCallback (OK=1538 KO=0 ) 2024-06-13 04:00:31,108 INFO > restLoginUsernamePasswordCallback (OK=1524 KO=0 ) 2024-06-13 04:00:31,108 INFO > PingOneProtectEvaluationCallback (OK=1460 KO=0 ) 2024-06-13 04:00:31,108 INFO > restloginProgressiveProfileCallback (OK=145 KO=0 ) 2024-06-13 04:00:31,108 INFO 2024-06-13 04:00:31,108 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 04:00:31,108 INFO [- ] 0% 2024-06-13 04:00:31,108 INFO waiting: 540162 / active: 108 / done: 1456 2024-06-13 04:00:31,108 INFO ================================================================================ 2024-06-13 04:00:31,108 INFO 2024-06-13 04:00:31,108 INFO 2024-06-13 04:00:31,108 INFO ================================================================================ 2024-06-13 04:00:31,108 INFO 2024-06-13 03:58:32 30s elapsed 2024-06-13 04:00:31,108 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 04:00:31,108 INFO > Global (OK=9001 KO=0 ) 2024-06-13 04:00:31,108 INFO > protectLoginInitiate (OK=2235 KO=0 ) 2024-06-13 04:00:31,108 INFO > PingOneProtectInitializeCallback (OK=2218 KO=0 ) 2024-06-13 04:00:31,108 INFO > restLoginUsernamePasswordCallback (OK=2200 KO=0 ) 2024-06-13 04:00:31,108 INFO > PingOneProtectEvaluationCallback (OK=2128 KO=0 ) 2024-06-13 04:00:31,108 INFO > restloginProgressiveProfileCallback (OK=220 KO=0 ) 2024-06-13 04:00:31,109 INFO 2024-06-13 04:00:31,109 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 04:00:31,109 INFO [- ] 0% 2024-06-13 04:00:31,109 INFO waiting: 539475 / active: 125 / done: 2126 2024-06-13 04:00:31,109 INFO ================================================================================ 2024-06-13 04:00:31,109 INFO 2024-06-13 04:00:31,109 INFO 2024-06-13 04:00:31,109 INFO [...] 2024-06-13 04:00:31,114 INFO > PingOneProtectInitializeCallback (OK=540968 KO=1 ) 2024-06-13 04:00:31,114 INFO > restLoginUsernamePasswordCallback (OK=540950 KO=3 ) 2024-06-13 04:00:31,114 INFO > PingOneProtectEvaluationCallback (OK=540866 KO=17 ) 2024-06-13 04:00:31,114 INFO > restloginProgressiveProfileCallback (OK=82551 KO=17 ) 2024-06-13 04:00:31,114 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 04:00:31,114 INFO > status.find.is(200), but actually found 401 19 (44.19%) 2024-06-13 04:00:31,114 INFO > jsonPath($.tokenId).find.exists, found nothing 15 (34.88%) 2024-06-13 04:00:31,114 INFO > status.find.is(200), but actually found 502 4 ( 9.30%) 2024-06-13 04:00:31,114 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 ( 2.33%) 2024-06-13 04:00:31,114 INFO attribute named 'callbacks' is defined 2024-06-13 04:00:31,114 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 ( 2.33%) 2024-06-13 04:00:31,114 INFO attribute named 'callbacks' is defined 2024-06-13 04:00:31,114 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 ( 2.33%) 2024-06-13 04:00:31,114 INFO attribute named 'callbacks' is defined 2024-06-13 04:00:31,114 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 ( 2.33%) 2024-06-13 04:00:31,114 INFO No attribute named 'callbacks' is defined 2024-06-13 04:00:31,115 INFO > status.find.is(200), but actually found 400 1 ( 2.33%) 2024-06-13 04:00:31,115 INFO 2024-06-13 04:00:31,115 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 04:00:31,115 INFO [#########################################################################-] 99% 2024-06-13 04:00:31,115 INFO waiting: 723 / active: 129 / done: 540874 2024-06-13 04:00:31,115 INFO ================================================================================ 2024-06-13 04:00:31,115 INFO 2024-06-13 04:00:31,115 INFO 2024-06-13 04:00:31,115 INFO ================================================================================ 2024-06-13 04:00:31,115 INFO 2024-06-13 04:58:32 3630s elapsed 2024-06-13 04:00:31,115 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 04:00:31,115 INFO > Global (OK=2249368 KO=39 ) 2024-06-13 04:00:31,115 INFO > protectLoginInitiate (OK=541713 KO=1 ) 2024-06-13 04:00:31,115 INFO > PingOneProtectInitializeCallback (OK=541698 KO=1 ) 2024-06-13 04:00:31,115 INFO > restLoginUsernamePasswordCallback (OK=541667 KO=3 ) 2024-06-13 04:00:31,115 INFO > PingOneProtectEvaluationCallback (OK=541587 KO=17 ) 2024-06-13 04:00:31,115 INFO > restloginProgressiveProfileCallback (OK=82703 KO=17 ) 2024-06-13 04:00:31,115 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 04:00:31,115 INFO > status.find.is(200), but actually found 401 19 (44.19%) 2024-06-13 04:00:31,115 INFO > jsonPath($.tokenId).find.exists, found nothing 15 (34.88%) 2024-06-13 04:00:31,115 INFO > status.find.is(200), but actually found 502 4 ( 9.30%) 2024-06-13 04:00:31,115 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 ( 2.33%) 2024-06-13 04:00:31,115 INFO attribute named 'callbacks' is defined 2024-06-13 04:00:31,115 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 ( 2.33%) 2024-06-13 04:00:31,115 INFO attribute named 'callbacks' is defined 2024-06-13 04:00:31,115 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 ( 2.33%) 2024-06-13 04:00:31,115 INFO attribute named 'callbacks' is defined 2024-06-13 04:00:31,115 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 ( 2.33%) 2024-06-13 04:00:31,115 INFO No attribute named 'callbacks' is defined 2024-06-13 04:00:31,115 INFO > status.find.is(200), but actually found 400 1 ( 2.33%) 2024-06-13 04:00:31,115 INFO 2024-06-13 04:00:31,115 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 04:00:31,115 INFO [#########################################################################-] 99% 2024-06-13 04:00:31,115 INFO waiting: 0 / active: 128 / done: 541598 2024-06-13 04:00:31,115 INFO ================================================================================ 2024-06-13 04:00:31,115 INFO 2024-06-13 04:00:31,115 INFO 2024-06-13 04:00:31,115 INFO ================================================================================ 2024-06-13 04:00:31,115 INFO 2024-06-13 04:58:33 3631s elapsed 2024-06-13 04:00:31,115 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 04:00:31,115 INFO > Global (OK=2249623 KO=39 ) 2024-06-13 04:00:31,116 INFO > protectLoginInitiate (OK=541725 KO=1 ) 2024-06-13 04:00:31,116 INFO > PingOneProtectInitializeCallback (OK=541724 KO=1 ) 2024-06-13 04:00:31,116 INFO > restLoginUsernamePasswordCallback (OK=541722 KO=3 ) 2024-06-13 04:00:31,116 INFO > PingOneProtectEvaluationCallback (OK=541708 KO=17 ) 2024-06-13 04:00:31,116 INFO > restloginProgressiveProfileCallback (OK=82744 KO=17 ) 2024-06-13 04:00:31,116 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 04:00:31,116 INFO > status.find.is(200), but actually found 401 19 (44.19%) 2024-06-13 04:00:31,116 INFO > jsonPath($.tokenId).find.exists, found nothing 15 (34.88%) 2024-06-13 04:00:31,116 INFO > status.find.is(200), but actually found 502 4 ( 9.30%) 2024-06-13 04:00:31,116 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 ( 2.33%) 2024-06-13 04:00:31,116 INFO attribute named 'callbacks' is defined 2024-06-13 04:00:31,116 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 ( 2.33%) 2024-06-13 04:00:31,116 INFO attribute named 'callbacks' is defined 2024-06-13 04:00:31,116 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 ( 2.33%) 2024-06-13 04:00:31,116 INFO attribute named 'callbacks' is defined 2024-06-13 04:00:31,116 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 ( 2.33%) 2024-06-13 04:00:31,116 INFO No attribute named 'callbacks' is defined 2024-06-13 04:00:31,116 INFO > status.find.is(200), but actually found 400 1 ( 2.33%) 2024-06-13 04:00:31,116 INFO 2024-06-13 04:00:31,116 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 04:00:31,116 INFO [##########################################################################]100% 2024-06-13 04:00:31,116 INFO waiting: 0 / active: 0 / done: 541726 2024-06-13 04:00:31,116 INFO ================================================================================ 2024-06-13 04:00:31,116 INFO 2024-06-13 04:00:31,116 INFO Simulation com.forgerock.gatling.Loadtest completed in 3631 seconds 2024-06-13 04:00:31,116 INFO 2024-06-13 04:00:31,116 INFO ---------------------- 2024-06-13 04:00:31,116 INFO [13/Jun/2024 04:58:33] Create tar file login-tree-150.tar.gz 2024-06-13 04:00:31,116 INFO ---------------------- 2024-06-13 04:00:31,116 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-13 04:00:31,116 INFO RESULT_FOLDER=login-tree-150 2024-06-13 04:00:31,116 INFO SIMULATION_PATH=login-tree-150/loadtest-20240613025800991 2024-06-13 04:00:31,116 INFO PWD=/results/gatling/login-tree-150/loadtest-20240613025800991 2024-06-13 04:00:31,116 INFO [cmd] tar -czvf /results/gatling//login-tree-150.tar.gz --exclude=simulation.log * 2024-06-13 04:00:31,116 INFO /results/gatling 2024-06-13 04:00:31,116 INFO 2024-06-13 04:00:31,116 INFO ---------------------- 2024-06-13 04:00:31,116 INFO [13/Jun/2024 04:58:33] Update /results/gatling//latest 2024-06-13 04:00:31,116 INFO ---------------------- 2024-06-13 04:00:31,116 INFO 2024-06-13 04:00:31,116 INFO ---------------------- 2024-06-13 04:00:31,117 INFO [13/Jun/2024 04:58:33] Update /results/gatling//latest.tar.gz 2024-06-13 04:00:31,117 INFO ---------------------- 2024-06-13 04:00:31,117 INFO ________________________________________________________________________________ [2024-06-13 04:00:31] login-tree-150 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-13 04:00:31,117 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/stderr.txt is small (0 lines), printing all lines: 2024-06-13 04:00:31,117 DEBUG ----- output ----- 2024-06-13 04:00:31,117 DEBUG 2024-06-13 04:00:31,117 INFO ________________________________________________________________________________ [2024-06-13 04:00:31] login-tree-150 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-13 04:00:31] login-tree-150 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-13 04:00:31] login-tree-150 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped