--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-23 05:48:34] login-tree-150 pre : N/A ________________________________________________________________________________ 2024-06-23 05:48:34 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-23 05:48:34 - 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-23 05:48:34,952 INFO 2024-06-23 05:48:34,952 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 05:48:34,952 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:35,282 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:35,283 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:35,283 DEBUG --- stdout --- 2024-06-23 05:48:35,283 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:35,283 DEBUG --- stderr --- 2024-06-23 05:48:35,283 DEBUG ________________________________________________________________________________ [2024-06-23 05:48:35] login-tree-150 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-23 05:48:35,284 INFO 2024-06-23 05:48:35,284 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 05:48:35,284 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:35,593 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:35,593 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:35,594 DEBUG --- stdout --- 2024-06-23 05:48:35,594 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:35,594 DEBUG --- stderr --- 2024-06-23 05:48:35,594 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-150 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-23 05:48:35] login-tree-150 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-23 05:48:35,595 INFO 2024-06-23 05:48:35,595 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 05:48:35,595 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:35,906 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:35,906 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:35,906 DEBUG --- stdout --- 2024-06-23 05:48:35,906 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:35,906 DEBUG --- stderr --- 2024-06-23 05:48:35,906 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_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-p1\" --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-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=150 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-23 05:48:35] 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-23 05:48:35,909 INFO 2024-06-23 05:48:35,909 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-23 05:48:35,909 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 05:48:36,146 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 05:48:36,146 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:36,146 DEBUG --- stdout --- 2024-06-23 05:48:36,146 DEBUG WAITING 2024-06-23 05:48:36,146 DEBUG --- stderr --- 2024-06-23 05:48:36,146 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-06-23 05:48:36] login-tree-150 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-23 05:48:36,147 INFO 2024-06-23 05:48:36,147 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 05:48:36,147 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:36,455 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:36,455 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:36,455 DEBUG --- stdout --- 2024-06-23 05:48:36,455 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:36,455 DEBUG --- stderr --- 2024-06-23 05:48:36,455 DEBUG 2024-06-23 05:48:36,456 INFO 2024-06-23 05:48:36,456 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/03_login-tree-150/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-23 05:48:36,456 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 05:48:37,897 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:37,897 DEBUG --- stdout --- 2024-06-23 05:48:37,897 DEBUG 2024-06-23 05:48:37,897 DEBUG --- stderr --- 2024-06-23 05:48:37,897 DEBUG Order has been successfully forwarded to overseer 2024-06-23 05:48:37 - INFO: Check if Gatling simulation started successfully 2024-06-23 05:48:37,898 INFO 2024-06-23 05:48:37,898 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 05:48:37,898 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:38,261 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:38,262 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:38,262 DEBUG --- stdout --- 2024-06-23 05:48:38,262 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:38,262 DEBUG --- stderr --- 2024-06-23 05:48:38,262 DEBUG 2024-06-23 05:48:38,262 INFO 2024-06-23 05:48:38,262 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-150/stdout.txt | grep "started..." 2024-06-23 05:48:38,262 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 05:48:39,038 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-23 05:48:44,782 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-23 05:48:44,782 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:44,782 DEBUG --- stdout --- 2024-06-23 05:48:44,782 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 05:48:44,782 DEBUG --- stderr --- 2024-06-23 05:48:44,782 DEBUG ________________________________________________________________________________ [2024-06-23 05:48:44] 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 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 06:50:54] login-tree-150 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-23 06:50:54,967 INFO 2024-06-23 06:50:54,968 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 06:50:54,968 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:50:55,302 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:50:55,302 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:50:55,302 DEBUG --- stdout --- 2024-06-23 06:50:55,302 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:50:55,302 DEBUG --- stderr --- 2024-06-23 06:50:55,302 DEBUG 2024-06-23 06:50:55,303 INFO 2024-06-23 06:50:55,303 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 06:50:55,303 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 06:50:56,094 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:50:56,094 DEBUG --- stdout --- 2024-06-23 06:50:56,096 DEBUG 2024-06-23 06:50:56,096 DEBUG --- stderr --- 2024-06-23 06:50:56,096 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-23 06:50:56] login-tree-150 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-23 06:50:56 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/order.json 2024-06-23 06:50:56,098 INFO 2024-06-23 06:50:56,098 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 06:50:56,099 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:50:56,417 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:50:56,418 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:50:56,418 DEBUG --- stdout --- 2024-06-23 06:50:56,418 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:50:56,418 DEBUG --- stderr --- 2024-06-23 06:50:56,418 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 06:50:56,418 INFO 2024-06-23 06:50:56,418 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 06:50:56,418 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:50:56,730 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:50:56,730 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:50:56,730 DEBUG --- stdout --- 2024-06-23 06:50:56,730 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:50:56,730 DEBUG --- stderr --- 2024-06-23 06:50:56,730 DEBUG 2024-06-23 06:50:56,731 INFO 2024-06-23 06:50:56,731 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-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-23 06:50:56,731 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 06:50:57,538 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 06:50:57,539 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:50:57,539 DEBUG --- stdout --- 2024-06-23 06:50:57,539 DEBUG /results/orders/login-tree-150/rc.txt file found 2024-06-23 06:50:57,539 DEBUG --- stderr --- 2024-06-23 06:50:57,539 DEBUG 2024-06-23 06:50:57,539 INFO 2024-06-23 06:50:57,539 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 06:50:57,539 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:50:57,861 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:50:57,861 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:50:57,861 DEBUG --- stdout --- 2024-06-23 06:50:57,861 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:50:57,861 DEBUG --- stderr --- 2024-06-23 06:50:57,861 DEBUG 2024-06-23 06:50:57,861 INFO 2024-06-23 06:50:57,862 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-150/rc.txt 2024-06-23 06:50:58,661 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 06:50:58,661 DEBUG --- stdout --- 2024-06-23 06:50:58,662 DEBUG 4 /results/orders/login-tree-150/rc.txt 2024-06-23 06:50:58,662 DEBUG --- stderr --- 2024-06-23 06:50:58,662 DEBUG 2024-06-23 06:50:58,662 INFO - Download file (size 4k) 2024-06-23 06:50:58,662 INFO 2024-06-23 06:50:58,662 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 06:50:58,662 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:50:58,968 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:50:58,968 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:50:58,969 DEBUG --- stdout --- 2024-06-23 06:50:58,969 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:50:58,969 DEBUG --- stderr --- 2024-06-23 06:50:58,969 DEBUG source : /results/orders/login-tree-150/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/rc.txt 2024-06-23 06:50:58,969 INFO 2024-06-23 06:50:58,969 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 06:50:58,969 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:50:59,270 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:50:59,270 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:50:59,270 DEBUG --- stdout --- 2024-06-23 06:50:59,270 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:50:59,270 DEBUG --- stderr --- 2024-06-23 06:50:59,270 DEBUG 2024-06-23 06:50:59,271 INFO 2024-06-23 06:50:59,271 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-150/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/rc.txt 2024-06-23 06:50:59,271 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 06:51:00,006 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:00,007 DEBUG --- stdout --- 2024-06-23 06:51:00,007 DEBUG tar: Removing leading `/' from member names 2024-06-23 06:51:00,007 DEBUG --- stderr --- 2024-06-23 06:51:00,007 DEBUG 2024-06-23 06:51:00,007 INFO 2024-06-23 06:51:00,007 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 06:51:00,007 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:00,330 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:00,330 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:00,330 DEBUG --- stdout --- 2024-06-23 06:51:00,330 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:00,330 DEBUG --- stderr --- 2024-06-23 06:51:00,330 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 06:51:00,331 INFO 2024-06-23 06:51:00,331 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 06:51:00,331 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:00,687 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:00,687 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:00,687 DEBUG --- stdout --- 2024-06-23 06:51:00,687 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:00,687 DEBUG --- stderr --- 2024-06-23 06:51:00,687 DEBUG 2024-06-23 06:51:00,688 INFO 2024-06-23 06:51:00,688 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-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-23 06:51:00,688 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 06:51:01,515 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 06:51:01,515 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:01,515 DEBUG --- stdout --- 2024-06-23 06:51:01,515 DEBUG /results/orders/login-tree-150/stderr.txt file found 2024-06-23 06:51:01,515 DEBUG --- stderr --- 2024-06-23 06:51:01,515 DEBUG 2024-06-23 06:51:01,516 INFO 2024-06-23 06:51:01,516 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 06:51:01,516 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:01,819 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:01,820 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:01,820 DEBUG --- stdout --- 2024-06-23 06:51:01,820 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:01,820 DEBUG --- stderr --- 2024-06-23 06:51:01,820 DEBUG 2024-06-23 06:51:01,820 INFO 2024-06-23 06:51:01,820 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-150/stderr.txt 2024-06-23 06:51:02,623 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 06:51:02,623 DEBUG --- stdout --- 2024-06-23 06:51:02,624 DEBUG 0 /results/orders/login-tree-150/stderr.txt 2024-06-23 06:51:02,624 DEBUG --- stderr --- 2024-06-23 06:51:02,624 DEBUG 2024-06-23 06:51:02,624 INFO - Download file (size 0k) 2024-06-23 06:51:02,624 INFO 2024-06-23 06:51:02,624 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 06:51:02,624 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:02,935 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:02,935 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:02,935 DEBUG --- stdout --- 2024-06-23 06:51:02,935 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:02,935 DEBUG --- stderr --- 2024-06-23 06:51:02,935 DEBUG source : /results/orders/login-tree-150/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/stderr.txt 2024-06-23 06:51:02,936 INFO 2024-06-23 06:51:02,936 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 06:51:02,936 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:03,241 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:03,241 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:03,241 DEBUG --- stdout --- 2024-06-23 06:51:03,241 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:03,241 DEBUG --- stderr --- 2024-06-23 06:51:03,242 DEBUG 2024-06-23 06:51:03,242 INFO 2024-06-23 06:51:03,242 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-150/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/stderr.txt 2024-06-23 06:51:03,242 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 06:51:03,974 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:03,975 DEBUG --- stdout --- 2024-06-23 06:51:03,975 DEBUG tar: Removing leading `/' from member names 2024-06-23 06:51:03,975 DEBUG --- stderr --- 2024-06-23 06:51:03,975 DEBUG 2024-06-23 06:51:03,976 INFO 2024-06-23 06:51:03,976 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 06:51:03,976 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:04,283 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:04,283 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:04,283 DEBUG --- stdout --- 2024-06-23 06:51:04,283 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:04,283 DEBUG --- stderr --- 2024-06-23 06:51:04,283 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 06:51:04,284 INFO 2024-06-23 06:51:04,284 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 06:51:04,284 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:04,591 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:04,591 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:04,591 DEBUG --- stdout --- 2024-06-23 06:51:04,591 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:04,591 DEBUG --- stderr --- 2024-06-23 06:51:04,591 DEBUG 2024-06-23 06:51:04,592 INFO 2024-06-23 06:51:04,592 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-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-23 06:51:04,592 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 06:51:05,398 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 06:51:05,399 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:05,399 DEBUG --- stdout --- 2024-06-23 06:51:05,399 DEBUG /results/orders/login-tree-150/stdout.txt file found 2024-06-23 06:51:05,399 DEBUG --- stderr --- 2024-06-23 06:51:05,399 DEBUG 2024-06-23 06:51:05,399 INFO 2024-06-23 06:51:05,399 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 06:51:05,399 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:05,709 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:05,710 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:05,710 DEBUG --- stdout --- 2024-06-23 06:51:05,710 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:05,710 DEBUG --- stderr --- 2024-06-23 06:51:05,710 DEBUG 2024-06-23 06:51:05,710 INFO 2024-06-23 06:51:05,710 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-150/stdout.txt 2024-06-23 06:51:06,515 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 06:51:06,516 DEBUG --- stdout --- 2024-06-23 06:51:06,517 DEBUG 1424 /results/orders/login-tree-150/stdout.txt 2024-06-23 06:51:06,518 DEBUG --- stderr --- 2024-06-23 06:51:06,518 DEBUG 2024-06-23 06:51:06,518 INFO - Download file (size 1424k) 2024-06-23 06:51:06,518 INFO 2024-06-23 06:51:06,518 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 06:51:06,518 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:06,824 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:06,825 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:06,825 DEBUG --- stdout --- 2024-06-23 06:51:06,825 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:06,825 DEBUG --- stderr --- 2024-06-23 06:51:06,825 DEBUG source : /results/orders/login-tree-150/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/stdout.txt 2024-06-23 06:51:06,825 INFO 2024-06-23 06:51:06,825 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 06:51:06,825 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:07,126 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:07,127 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:07,127 DEBUG --- stdout --- 2024-06-23 06:51:07,127 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:07,127 DEBUG --- stderr --- 2024-06-23 06:51:07,127 DEBUG 2024-06-23 06:51:07,127 INFO 2024-06-23 06:51:07,128 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-150/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/stdout.txt 2024-06-23 06:51:07,128 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 06:51:08,315 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:08,315 DEBUG --- stdout --- 2024-06-23 06:51:08,316 DEBUG tar: Removing leading `/' from member names 2024-06-23 06:51:08,316 DEBUG --- stderr --- 2024-06-23 06:51:08,316 DEBUG ________________________________________________________________________________ [2024-06-23 06:51:08] login-tree-150 show_cmd : Display order command ________________________________________________________________________________ 2024-06-23 06:51:08,316 INFO 2024-06-23 06:51:08,316 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 06:51:08,316 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:08,638 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:08,638 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:08,638 DEBUG --- stdout --- 2024-06-23 06:51:08,638 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:08,638 DEBUG --- stderr --- 2024-06-23 06:51:08,638 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 06:51:08,639 INFO 2024-06-23 06:51:08,639 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 06:51:08,639 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:08,963 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:08,963 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:08,963 DEBUG --- stdout --- 2024-06-23 06:51:08,963 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:08,963 DEBUG --- stderr --- 2024-06-23 06:51:08,963 DEBUG 2024-06-23 06:51:08,964 INFO 2024-06-23 06:51:08,964 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-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-23 06:51:08,964 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 06:51:09,782 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 06:51:09,782 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:09,782 DEBUG --- stdout --- 2024-06-23 06:51:09,782 DEBUG /results/orders/login-tree-150/cmd.txt file found 2024-06-23 06:51:09,782 DEBUG --- stderr --- 2024-06-23 06:51:09,782 DEBUG 2024-06-23 06:51:09,783 INFO 2024-06-23 06:51:09,783 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 06:51:09,783 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:10,102 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:10,102 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:10,102 DEBUG --- stdout --- 2024-06-23 06:51:10,103 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:10,103 DEBUG --- stderr --- 2024-06-23 06:51:10,103 DEBUG 2024-06-23 06:51:10,103 INFO 2024-06-23 06:51:10,103 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-150/cmd.txt 2024-06-23 06:51:10,914 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 06:51:10,915 DEBUG --- stdout --- 2024-06-23 06:51:10,915 DEBUG 4 /results/orders/login-tree-150/cmd.txt 2024-06-23 06:51:10,915 DEBUG --- stderr --- 2024-06-23 06:51:10,915 DEBUG 2024-06-23 06:51:10,915 INFO - Download file (size 4k) 2024-06-23 06:51:10,915 INFO 2024-06-23 06:51:10,915 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 06:51:10,915 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:11,218 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:11,218 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:11,219 DEBUG --- stdout --- 2024-06-23 06:51:11,219 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:11,219 DEBUG --- stderr --- 2024-06-23 06:51:11,219 DEBUG source : /results/orders/login-tree-150/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/cmd.txt 2024-06-23 06:51:11,219 INFO 2024-06-23 06:51:11,219 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 06:51:11,219 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:11,518 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:11,519 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:11,520 DEBUG --- stdout --- 2024-06-23 06:51:11,520 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:11,520 DEBUG --- stderr --- 2024-06-23 06:51:11,520 DEBUG 2024-06-23 06:51:11,521 INFO 2024-06-23 06:51:11,521 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-150/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/cmd.txt 2024-06-23 06:51:11,521 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 06:51:12,257 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:12,257 DEBUG --- stdout --- 2024-06-23 06:51:12,257 DEBUG tar: Removing leading `/' from member names 2024-06-23 06:51:12,257 DEBUG --- stderr --- 2024-06-23 06:51:12,257 DEBUG 2024-06-23 06:51:12,258 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/cmd.txt is small (1 lines), printing all lines: 2024-06-23 06:51:12,258 DEBUG ----- output ----- 2024-06-23 06:51:12,258 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-150 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-23 06:51:12,258 INFO ________________________________________________________________________________ [2024-06-23 06:51:12] login-tree-150 show_rc : Display rc order ________________________________________________________________________________ 2024-06-23 06:51:12,258 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/rc.txt is small (1 lines), printing all lines: 2024-06-23 06:51:12,258 DEBUG ----- output ----- 2024-06-23 06:51:12,258 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-23 06:51:12,258 INFO ________________________________________________________________________________ [2024-06-23 06:51:12] login-tree-150 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-23 06:51:12,263 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/03_login-tree-150/stdout.txt is 19352 lines): 2024-06-23 06:51:12,263 DEBUG ----- output ----- 2024-06-23 06:51:12,263 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-23 06:51:12,263 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-23 06:51:12,263 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 06:51:12,263 INFO 2024-06-23 06:51:12,263 INFO ================================================================================ 2024-06-23 06:51:12,263 INFO 2024-06-23 06:48:47 5s elapsed 2024-06-23 06:51:12,263 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 06:51:12,263 INFO > Global (OK=239 KO=0 ) 2024-06-23 06:51:12,263 INFO > protectLoginInitiate (OK=62 KO=0 ) 2024-06-23 06:51:12,263 INFO > PingOneProtectInitializeCallback (OK=61 KO=0 ) 2024-06-23 06:51:12,263 INFO > restLoginUsernamePasswordCallback (OK=59 KO=0 ) 2024-06-23 06:51:12,263 INFO > PingOneProtectEvaluationCallback (OK=53 KO=0 ) 2024-06-23 06:51:12,263 INFO > restloginProgressiveProfileCallback (OK=4 KO=0 ) 2024-06-23 06:51:12,263 INFO 2024-06-23 06:51:12,263 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 06:51:12,263 INFO [- ] 0% 2024-06-23 06:51:12,263 INFO waiting: 542626 / active: 10 / done: 53 2024-06-23 06:51:12,263 INFO ================================================================================ 2024-06-23 06:51:12,263 INFO 2024-06-23 06:51:12,263 INFO 2024-06-23 06:51:12,263 INFO ================================================================================ 2024-06-23 06:51:12,263 INFO 2024-06-23 06:48:52 10s elapsed 2024-06-23 06:51:12,263 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 06:51:12,263 INFO > Global (OK=984 KO=0 ) 2024-06-23 06:51:12,264 INFO > protectLoginInitiate (OK=249 KO=0 ) 2024-06-23 06:51:12,264 INFO > PingOneProtectInitializeCallback (OK=246 KO=0 ) 2024-06-23 06:51:12,264 INFO > restLoginUsernamePasswordCallback (OK=244 KO=0 ) 2024-06-23 06:51:12,264 INFO > PingOneProtectEvaluationCallback (OK=233 KO=0 ) 2024-06-23 06:51:12,264 INFO > restloginProgressiveProfileCallback (OK=12 KO=0 ) 2024-06-23 06:51:12,264 INFO 2024-06-23 06:51:12,264 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 06:51:12,264 INFO [- ] 0% 2024-06-23 06:51:12,264 INFO waiting: 542438 / active: 18 / done: 233 2024-06-23 06:51:12,264 INFO ================================================================================ 2024-06-23 06:51:12,264 INFO 2024-06-23 06:51:12,264 INFO 2024-06-23 06:51:12,264 INFO ================================================================================ 2024-06-23 06:51:12,264 INFO 2024-06-23 06:48:57 15s elapsed 2024-06-23 06:51:12,264 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 06:51:12,264 INFO > Global (OK=2250 KO=0 ) 2024-06-23 06:51:12,264 INFO > protectLoginInitiate (OK=560 KO=0 ) 2024-06-23 06:51:12,264 INFO > PingOneProtectInitializeCallback (OK=557 KO=0 ) 2024-06-23 06:51:12,264 INFO > restLoginUsernamePasswordCallback (OK=553 KO=0 ) 2024-06-23 06:51:12,264 INFO > PingOneProtectEvaluationCallback (OK=534 KO=0 ) 2024-06-23 06:51:12,264 INFO > restloginProgressiveProfileCallback (OK=46 KO=0 ) 2024-06-23 06:51:12,264 INFO 2024-06-23 06:51:12,264 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 06:51:12,264 INFO [- ] 0% 2024-06-23 06:51:12,264 INFO waiting: 542125 / active: 30 / done: 534 2024-06-23 06:51:12,264 INFO ================================================================================ 2024-06-23 06:51:12,264 INFO 2024-06-23 06:51:12,264 INFO 2024-06-23 06:51:12,264 INFO ================================================================================ 2024-06-23 06:51:12,264 INFO 2024-06-23 06:49:02 20s elapsed 2024-06-23 06:51:12,264 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 06:51:12,264 INFO > Global (OK=4001 KO=0 ) 2024-06-23 06:51:12,264 INFO > protectLoginInitiate (OK=997 KO=0 ) 2024-06-23 06:51:12,264 INFO > PingOneProtectInitializeCallback (OK=992 KO=0 ) 2024-06-23 06:51:12,264 INFO > restLoginUsernamePasswordCallback (OK=987 KO=0 ) 2024-06-23 06:51:12,264 INFO > PingOneProtectEvaluationCallback (OK=963 KO=0 ) 2024-06-23 06:51:12,264 INFO > restloginProgressiveProfileCallback (OK=62 KO=0 ) 2024-06-23 06:51:12,264 INFO 2024-06-23 06:51:12,264 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 06:51:12,264 INFO [- ] 0% 2024-06-23 06:51:12,264 INFO waiting: 541687 / active: 40 / done: 962 2024-06-23 06:51:12,265 INFO ================================================================================ 2024-06-23 06:51:12,265 INFO 2024-06-23 06:51:12,265 INFO 2024-06-23 06:51:12,265 INFO ================================================================================ 2024-06-23 06:51:12,265 INFO 2024-06-23 06:49:07 25s elapsed 2024-06-23 06:51:12,265 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 06:51:12,265 INFO > Global (OK=6262 KO=0 ) 2024-06-23 06:51:12,265 INFO > protectLoginInitiate (OK=1558 KO=0 ) 2024-06-23 06:51:12,265 INFO > PingOneProtectInitializeCallback (OK=1553 KO=0 ) 2024-06-23 06:51:12,265 INFO > restLoginUsernamePasswordCallback (OK=1546 KO=0 ) 2024-06-23 06:51:12,265 INFO > PingOneProtectEvaluationCallback (OK=1513 KO=0 ) 2024-06-23 06:51:12,265 INFO > restloginProgressiveProfileCallback (OK=92 KO=0 ) 2024-06-23 06:51:12,265 INFO 2024-06-23 06:51:12,265 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 06:51:12,265 INFO [- ] 0% 2024-06-23 06:51:12,265 INFO waiting: 541124 / active: 53 / done: 1512 2024-06-23 06:51:12,265 INFO ================================================================================ 2024-06-23 06:51:12,265 INFO 2024-06-23 06:51:12,265 INFO 2024-06-23 06:51:12,265 INFO ================================================================================ 2024-06-23 06:51:12,265 INFO 2024-06-23 06:49:12 30s elapsed 2024-06-23 06:51:12,265 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 06:51:12,265 INFO > Global (OK=9031 KO=0 ) 2024-06-23 06:51:12,265 INFO > protectLoginInitiate (OK=2245 KO=0 ) 2024-06-23 06:51:12,265 INFO > PingOneProtectInitializeCallback (OK=2237 KO=0 ) 2024-06-23 06:51:12,265 INFO > restLoginUsernamePasswordCallback (OK=2231 KO=0 ) 2024-06-23 06:51:12,265 INFO > PingOneProtectEvaluationCallback (OK=2189 KO=0 ) 2024-06-23 06:51:12,265 INFO > restloginProgressiveProfileCallback (OK=129 KO=0 ) 2024-06-23 06:51:12,265 INFO 2024-06-23 06:51:12,265 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 06:51:12,265 INFO [- ] 0% 2024-06-23 06:51:12,265 INFO waiting: 540432 / active: 69 / done: 2188 2024-06-23 06:51:12,265 INFO ================================================================================ 2024-06-23 06:51:12,265 INFO 2024-06-23 06:51:12,265 INFO 2024-06-23 06:51:12,265 INFO [...] 2024-06-23 06:51:12,271 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 06:51:12,271 INFO > Global (OK=2229233 KO=40 ) 2024-06-23 06:51:12,271 INFO > protectLoginInitiate (OK=541952 KO=2 ) 2024-06-23 06:51:12,271 INFO > PingOneProtectInitializeCallback (OK=541943 KO=1 ) 2024-06-23 06:51:12,271 INFO > restLoginUsernamePasswordCallback (OK=541931 KO=7 ) 2024-06-23 06:51:12,271 INFO > PingOneProtectEvaluationCallback (OK=541878 KO=16 ) 2024-06-23 06:51:12,271 INFO > restloginProgressiveProfileCallback (OK=61529 KO=14 ) 2024-06-23 06:51:12,271 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 06:51:12,271 INFO > status.find.is(200), but actually found 401 14 (29.17%) 2024-06-23 06:51:12,271 INFO > status.find.is(200), but actually found 502 14 (29.17%) 2024-06-23 06:51:12,271 INFO > jsonPath($.tokenId).find.exists, found nothing 12 (25.00%) 2024-06-23 06:51:12,271 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 4.17%) 2024-06-23 06:51:12,271 INFO attribute named 'callbacks' is defined 2024-06-23 06:51:12,271 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 4.17%) 2024-06-23 06:51:12,271 INFO attribute named 'callbacks' is defined 2024-06-23 06:51:12,271 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 4.17%) 2024-06-23 06:51:12,271 INFO attribute named 'callbacks' is defined 2024-06-23 06:51:12,271 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 4.17%) 2024-06-23 06:51:12,271 INFO No attribute named 'callbacks' is defined 2024-06-23 06:51:12,271 INFO 2024-06-23 06:51:12,271 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 06:51:12,271 INFO [#########################################################################-] 99% 2024-06-23 06:51:12,271 INFO waiting: 727 / active: 70 / done: 541892 2024-06-23 06:51:12,271 INFO ================================================================================ 2024-06-23 06:51:12,271 INFO 2024-06-23 06:51:12,271 INFO 2024-06-23 06:51:12,271 INFO ================================================================================ 2024-06-23 06:51:12,271 INFO 2024-06-23 07:49:12 3630s elapsed 2024-06-23 06:51:12,271 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 06:51:12,271 INFO > Global (OK=2232327 KO=40 ) 2024-06-23 06:51:12,271 INFO > protectLoginInitiate (OK=542684 KO=2 ) 2024-06-23 06:51:12,272 INFO > PingOneProtectInitializeCallback (OK=542682 KO=1 ) 2024-06-23 06:51:12,272 INFO > restLoginUsernamePasswordCallback (OK=542671 KO=7 ) 2024-06-23 06:51:12,272 INFO > PingOneProtectEvaluationCallback (OK=542624 KO=16 ) 2024-06-23 06:51:12,272 INFO > restloginProgressiveProfileCallback (OK=61666 KO=14 ) 2024-06-23 06:51:12,272 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 06:51:12,272 INFO > status.find.is(200), but actually found 401 14 (29.17%) 2024-06-23 06:51:12,272 INFO > status.find.is(200), but actually found 502 14 (29.17%) 2024-06-23 06:51:12,272 INFO > jsonPath($.tokenId).find.exists, found nothing 12 (25.00%) 2024-06-23 06:51:12,272 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 4.17%) 2024-06-23 06:51:12,272 INFO attribute named 'callbacks' is defined 2024-06-23 06:51:12,272 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 4.17%) 2024-06-23 06:51:12,272 INFO attribute named 'callbacks' is defined 2024-06-23 06:51:12,272 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 4.17%) 2024-06-23 06:51:12,272 INFO attribute named 'callbacks' is defined 2024-06-23 06:51:12,272 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 4.17%) 2024-06-23 06:51:12,272 INFO No attribute named 'callbacks' is defined 2024-06-23 06:51:12,272 INFO 2024-06-23 06:51:12,272 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 06:51:12,272 INFO [#########################################################################-] 99% 2024-06-23 06:51:12,272 INFO waiting: 0 / active: 50 / done: 542639 2024-06-23 06:51:12,272 INFO ================================================================================ 2024-06-23 06:51:12,272 INFO 2024-06-23 06:51:12,272 INFO 2024-06-23 06:51:12,272 INFO ================================================================================ 2024-06-23 06:51:12,272 INFO 2024-06-23 07:49:12 3630s elapsed 2024-06-23 06:51:12,272 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 06:51:12,272 INFO > Global (OK=2232402 KO=40 ) 2024-06-23 06:51:12,272 INFO > protectLoginInitiate (OK=542687 KO=2 ) 2024-06-23 06:51:12,272 INFO > PingOneProtectInitializeCallback (OK=542686 KO=1 ) 2024-06-23 06:51:12,272 INFO > restLoginUsernamePasswordCallback (OK=542680 KO=7 ) 2024-06-23 06:51:12,272 INFO > PingOneProtectEvaluationCallback (OK=542671 KO=16 ) 2024-06-23 06:51:12,272 INFO > restloginProgressiveProfileCallback (OK=61678 KO=14 ) 2024-06-23 06:51:12,272 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 06:51:12,272 INFO > status.find.is(200), but actually found 401 14 (29.17%) 2024-06-23 06:51:12,272 INFO > status.find.is(200), but actually found 502 14 (29.17%) 2024-06-23 06:51:12,272 INFO > jsonPath($.tokenId).find.exists, found nothing 12 (25.00%) 2024-06-23 06:51:12,272 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 4.17%) 2024-06-23 06:51:12,272 INFO attribute named 'callbacks' is defined 2024-06-23 06:51:12,272 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 4.17%) 2024-06-23 06:51:12,272 INFO attribute named 'callbacks' is defined 2024-06-23 06:51:12,273 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 4.17%) 2024-06-23 06:51:12,273 INFO attribute named 'callbacks' is defined 2024-06-23 06:51:12,273 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 4.17%) 2024-06-23 06:51:12,273 INFO No attribute named 'callbacks' is defined 2024-06-23 06:51:12,273 INFO 2024-06-23 06:51:12,273 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 06:51:12,273 INFO [##########################################################################]100% 2024-06-23 06:51:12,273 INFO waiting: 0 / active: 0 / done: 542689 2024-06-23 06:51:12,273 INFO ================================================================================ 2024-06-23 06:51:12,273 INFO 2024-06-23 06:51:12,273 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-23 06:51:12,273 INFO 2024-06-23 06:51:12,273 INFO ---------------------- 2024-06-23 06:51:12,273 INFO [23/Jun/2024 07:49:12] Create tar file login-tree-150.tar.gz 2024-06-23 06:51:12,273 INFO ---------------------- 2024-06-23 06:51:12,273 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-23 06:51:12,273 INFO RESULT_FOLDER=login-tree-150 2024-06-23 06:51:12,273 INFO SIMULATION_PATH=login-tree-150/loadtest-20240623054841601 2024-06-23 06:51:12,273 INFO PWD=/results/gatling/login-tree-150/loadtest-20240623054841601 2024-06-23 06:51:12,273 INFO [cmd] tar -czvf /results/gatling//login-tree-150.tar.gz --exclude=simulation.log * 2024-06-23 06:51:12,273 INFO /results/gatling 2024-06-23 06:51:12,273 INFO 2024-06-23 06:51:12,273 INFO ---------------------- 2024-06-23 06:51:12,273 INFO [23/Jun/2024 07:49:12] Update /results/gatling//latest 2024-06-23 06:51:12,273 INFO ---------------------- 2024-06-23 06:51:12,273 INFO 2024-06-23 06:51:12,273 INFO ---------------------- 2024-06-23 06:51:12,273 INFO [23/Jun/2024 07:49:12] Update /results/gatling//latest.tar.gz 2024-06-23 06:51:12,273 INFO ---------------------- 2024-06-23 06:51:12,273 INFO ________________________________________________________________________________ [2024-06-23 06:51:12] login-tree-150 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-23 06:51:12,274 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/stderr.txt is small (0 lines), printing all lines: 2024-06-23 06:51:12,274 DEBUG ----- output ----- 2024-06-23 06:51:12,274 DEBUG 2024-06-23 06:51:12,274 INFO ________________________________________________________________________________ [2024-06-23 06:51:12] 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-23 06:51:12] login-tree-150 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-23 06:51:12] login-tree-150 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped