--Task-- name: login-tree-200 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-150'] preceding_task: login-tree-150 options: {'testCase': 'idc.loginProtect', 'testMode': 'open', 'throughput': 200, '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-22 06:28:12] login-tree-200 pre : N/A ________________________________________________________________________________ 2024-06-22 06:28:12 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-22 06:28:12 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-200/stdout.txt Pod log : /results/orders/login-tree-200 2024-06-22 06:28:12,938 INFO 2024-06-22 06:28:12,938 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-22 06:28:12,938 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:13,254 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:13,255 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:13,255 DEBUG --- stdout --- 2024-06-22 06:28:13,255 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:13,255 DEBUG --- stderr --- 2024-06-22 06:28:13,255 DEBUG ________________________________________________________________________________ [2024-06-22 06:28:13] login-tree-200 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-22 06:28:13,256 INFO 2024-06-22 06:28:13,256 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-22 06:28:13,256 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:13,574 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:13,574 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:13,574 DEBUG --- stdout --- 2024-06-22 06:28:13,574 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:13,575 DEBUG --- stderr --- 2024-06-22 06:28:13,575 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-200 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-22 06:28:13] login-tree-200 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-22 06:28:13,575 INFO 2024-06-22 06:28:13,575 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-22 06:28:13,576 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:13,895 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:13,895 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:13,895 DEBUG --- stdout --- 2024-06-22 06:28:13,895 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:13,895 DEBUG --- stderr --- 2024-06-22 06:28:13,896 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/order.json { "name": "login-tree-200", "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-200 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-200", "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=200 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-22 06:28:13] login-tree-200 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-22 06:28:13,898 INFO 2024-06-22 06:28:13,898 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-22 06:28:13,898 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 06:28:14,147 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 06:28:14,148 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:14,148 DEBUG --- stdout --- 2024-06-22 06:28:14,148 DEBUG WAITING 2024-06-22 06:28:14,148 DEBUG --- stderr --- 2024-06-22 06:28:14,148 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-22 06:28:14] login-tree-200 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-22 06:28:14,149 INFO 2024-06-22 06:28:14,149 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-22 06:28:14,149 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:14,458 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:14,458 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:14,458 DEBUG --- stdout --- 2024-06-22 06:28:14,458 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:14,458 DEBUG --- stderr --- 2024-06-22 06:28:14,458 DEBUG 2024-06-22 06:28:14,458 INFO 2024-06-22 06:28:14,459 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-200/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-22 06:28:14,459 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 06:28:15,814 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:15,815 DEBUG --- stdout --- 2024-06-22 06:28:15,815 DEBUG 2024-06-22 06:28:15,815 DEBUG --- stderr --- 2024-06-22 06:28:15,815 DEBUG Order has been successfully forwarded to overseer 2024-06-22 06:28:15 - INFO: Check if Gatling simulation started successfully 2024-06-22 06:28:15,815 INFO 2024-06-22 06:28:15,815 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-22 06:28:15,815 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:16,137 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:16,137 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:16,137 DEBUG --- stdout --- 2024-06-22 06:28:16,137 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:16,137 DEBUG --- stderr --- 2024-06-22 06:28:16,137 DEBUG 2024-06-22 06:28:16,138 INFO 2024-06-22 06:28:16,138 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-200/stdout.txt | grep "started..." 2024-06-22 06:28:16,138 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 06:28:16,885 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-22 06:28:22,683 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-22 06:28:22,683 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:22,683 DEBUG --- stdout --- 2024-06-22 06:28:22,683 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-22 06:28:22,683 DEBUG --- stderr --- 2024-06-22 06:28:22,683 DEBUG ________________________________________________________________________________ [2024-06-22 06:28:22] login-tree-200 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 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, 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-22 07:30:32] login-tree-200 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-22 07:30:32,901 INFO 2024-06-22 07:30:32,901 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-22 07:30:32,902 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:33,256 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:33,256 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:33,256 DEBUG --- stdout --- 2024-06-22 07:30:33,256 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:33,256 DEBUG --- stderr --- 2024-06-22 07:30:33,256 DEBUG 2024-06-22 07:30:33,257 INFO 2024-06-22 07:30:33,257 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-22 07:30:33,257 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 07:30:34,052 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:34,053 DEBUG --- stdout --- 2024-06-22 07:30:34,053 DEBUG 2024-06-22 07:30:34,053 DEBUG --- stderr --- 2024-06-22 07:30:34,053 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-22 07:30:34] login-tree-200 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-22 07:30:34 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/order.json 2024-06-22 07:30:34,054 INFO 2024-06-22 07:30:34,054 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-22 07:30:34,055 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:34,367 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:34,367 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:34,367 DEBUG --- stdout --- 2024-06-22 07:30:34,367 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:34,367 DEBUG --- stderr --- 2024-06-22 07:30:34,368 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 07:30:34,368 INFO 2024-06-22 07:30:34,368 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-22 07:30:34,368 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:34,676 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:34,676 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:34,676 DEBUG --- stdout --- 2024-06-22 07:30:34,676 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:34,676 DEBUG --- stderr --- 2024-06-22 07:30:34,676 DEBUG 2024-06-22 07:30:34,677 INFO 2024-06-22 07:30:34,677 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-200/rc.txt" ]; then echo "/results/orders/login-tree-200/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-200/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/rc.txt file found" 2024-06-22 07:30:34,677 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 07:30:35,495 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 07:30:35,495 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:35,495 DEBUG --- stdout --- 2024-06-22 07:30:35,495 DEBUG /results/orders/login-tree-200/rc.txt file found 2024-06-22 07:30:35,495 DEBUG --- stderr --- 2024-06-22 07:30:35,495 DEBUG 2024-06-22 07:30:35,496 INFO 2024-06-22 07:30:35,496 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-22 07:30:35,496 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:35,824 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:35,824 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:35,824 DEBUG --- stdout --- 2024-06-22 07:30:35,824 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:35,824 DEBUG --- stderr --- 2024-06-22 07:30:35,824 DEBUG 2024-06-22 07:30:35,824 INFO 2024-06-22 07:30:35,825 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-200/rc.txt 2024-06-22 07:30:36,645 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 07:30:36,645 DEBUG --- stdout --- 2024-06-22 07:30:36,646 DEBUG 4 /results/orders/login-tree-200/rc.txt 2024-06-22 07:30:36,646 DEBUG --- stderr --- 2024-06-22 07:30:36,646 DEBUG 2024-06-22 07:30:36,646 INFO - Download file (size 4k) 2024-06-22 07:30:36,646 INFO 2024-06-22 07:30:36,646 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-22 07:30:36,646 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:36,954 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:36,954 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:36,954 DEBUG --- stdout --- 2024-06-22 07:30:36,954 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:36,954 DEBUG --- stderr --- 2024-06-22 07:30:36,954 DEBUG source : /results/orders/login-tree-200/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/rc.txt 2024-06-22 07:30:36,955 INFO 2024-06-22 07:30:36,955 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-22 07:30:36,955 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:37,259 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:37,260 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:37,260 DEBUG --- stdout --- 2024-06-22 07:30:37,260 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:37,260 DEBUG --- stderr --- 2024-06-22 07:30:37,260 DEBUG 2024-06-22 07:30:37,260 INFO 2024-06-22 07:30:37,260 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-200/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/rc.txt 2024-06-22 07:30:37,261 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 07:30:38,010 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:38,010 DEBUG --- stdout --- 2024-06-22 07:30:38,010 DEBUG tar: Removing leading `/' from member names 2024-06-22 07:30:38,010 DEBUG --- stderr --- 2024-06-22 07:30:38,010 DEBUG 2024-06-22 07:30:38,010 INFO 2024-06-22 07:30:38,010 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-22 07:30:38,010 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:38,317 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:38,317 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:38,317 DEBUG --- stdout --- 2024-06-22 07:30:38,317 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:38,317 DEBUG --- stderr --- 2024-06-22 07:30:38,318 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 07:30:38,318 INFO 2024-06-22 07:30:38,318 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-22 07:30:38,318 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:38,622 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:38,623 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:38,623 DEBUG --- stdout --- 2024-06-22 07:30:38,623 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:38,623 DEBUG --- stderr --- 2024-06-22 07:30:38,623 DEBUG 2024-06-22 07:30:38,624 INFO 2024-06-22 07:30:38,624 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-200/stderr.txt" ]; then echo "/results/orders/login-tree-200/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-200/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/stderr.txt file found" 2024-06-22 07:30:38,624 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 07:30:39,438 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 07:30:39,438 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:39,438 DEBUG --- stdout --- 2024-06-22 07:30:39,438 DEBUG /results/orders/login-tree-200/stderr.txt file found 2024-06-22 07:30:39,438 DEBUG --- stderr --- 2024-06-22 07:30:39,438 DEBUG 2024-06-22 07:30:39,439 INFO 2024-06-22 07:30:39,439 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-22 07:30:39,439 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:39,764 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:39,764 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:39,764 DEBUG --- stdout --- 2024-06-22 07:30:39,764 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:39,764 DEBUG --- stderr --- 2024-06-22 07:30:39,764 DEBUG 2024-06-22 07:30:39,764 INFO 2024-06-22 07:30:39,765 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-200/stderr.txt 2024-06-22 07:30:40,572 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 07:30:40,572 DEBUG --- stdout --- 2024-06-22 07:30:40,572 DEBUG 0 /results/orders/login-tree-200/stderr.txt 2024-06-22 07:30:40,572 DEBUG --- stderr --- 2024-06-22 07:30:40,572 DEBUG 2024-06-22 07:30:40,572 INFO - Download file (size 0k) 2024-06-22 07:30:40,573 INFO 2024-06-22 07:30:40,573 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-22 07:30:40,573 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:40,904 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:40,904 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:40,904 DEBUG --- stdout --- 2024-06-22 07:30:40,904 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:40,904 DEBUG --- stderr --- 2024-06-22 07:30:40,904 DEBUG source : /results/orders/login-tree-200/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stderr.txt 2024-06-22 07:30:40,904 INFO 2024-06-22 07:30:40,904 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-22 07:30:40,904 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:41,223 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:41,223 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:41,223 DEBUG --- stdout --- 2024-06-22 07:30:41,223 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:41,223 DEBUG --- stderr --- 2024-06-22 07:30:41,223 DEBUG 2024-06-22 07:30:41,224 INFO 2024-06-22 07:30:41,224 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-200/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stderr.txt 2024-06-22 07:30:41,224 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 07:30:41,965 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:41,965 DEBUG --- stdout --- 2024-06-22 07:30:41,965 DEBUG tar: Removing leading `/' from member names 2024-06-22 07:30:41,965 DEBUG --- stderr --- 2024-06-22 07:30:41,965 DEBUG 2024-06-22 07:30:41,966 INFO 2024-06-22 07:30:41,966 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-22 07:30:41,966 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:42,269 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:42,269 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:42,269 DEBUG --- stdout --- 2024-06-22 07:30:42,269 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:42,269 DEBUG --- stderr --- 2024-06-22 07:30:42,269 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 07:30:42,270 INFO 2024-06-22 07:30:42,270 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-22 07:30:42,270 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:42,575 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:42,575 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:42,575 DEBUG --- stdout --- 2024-06-22 07:30:42,575 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:42,575 DEBUG --- stderr --- 2024-06-22 07:30:42,575 DEBUG 2024-06-22 07:30:42,576 INFO 2024-06-22 07:30:42,576 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-200/stdout.txt" ]; then echo "/results/orders/login-tree-200/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-200/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/stdout.txt file found" 2024-06-22 07:30:42,576 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 07:30:43,386 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 07:30:43,387 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:43,388 DEBUG --- stdout --- 2024-06-22 07:30:43,388 DEBUG /results/orders/login-tree-200/stdout.txt file found 2024-06-22 07:30:43,388 DEBUG --- stderr --- 2024-06-22 07:30:43,388 DEBUG 2024-06-22 07:30:43,389 INFO 2024-06-22 07:30:43,389 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-22 07:30:43,389 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:43,713 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:43,714 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:43,714 DEBUG --- stdout --- 2024-06-22 07:30:43,714 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:43,714 DEBUG --- stderr --- 2024-06-22 07:30:43,714 DEBUG 2024-06-22 07:30:43,714 INFO 2024-06-22 07:30:43,714 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-200/stdout.txt 2024-06-22 07:30:44,530 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 07:30:44,530 DEBUG --- stdout --- 2024-06-22 07:30:44,531 DEBUG 1920 /results/orders/login-tree-200/stdout.txt 2024-06-22 07:30:44,531 DEBUG --- stderr --- 2024-06-22 07:30:44,531 DEBUG 2024-06-22 07:30:44,531 INFO - Download file (size 1920k) 2024-06-22 07:30:44,531 INFO 2024-06-22 07:30:44,531 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-22 07:30:44,531 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:44,852 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:44,853 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:44,853 DEBUG --- stdout --- 2024-06-22 07:30:44,853 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:44,853 DEBUG --- stderr --- 2024-06-22 07:30:44,853 DEBUG source : /results/orders/login-tree-200/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stdout.txt 2024-06-22 07:30:44,853 INFO 2024-06-22 07:30:44,853 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 07:30:44,853 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:45,169 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:45,170 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:45,170 DEBUG --- stdout --- 2024-06-22 07:30:45,170 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:45,170 DEBUG --- stderr --- 2024-06-22 07:30:45,170 DEBUG 2024-06-22 07:30:45,171 INFO 2024-06-22 07:30:45,171 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-200/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stdout.txt 2024-06-22 07:30:45,171 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 07:30:46,354 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:46,354 DEBUG --- stdout --- 2024-06-22 07:30:46,354 DEBUG tar: Removing leading `/' from member names 2024-06-22 07:30:46,355 DEBUG --- stderr --- 2024-06-22 07:30:46,355 DEBUG ________________________________________________________________________________ [2024-06-22 07:30:46] login-tree-200 show_cmd : Display order command ________________________________________________________________________________ 2024-06-22 07:30:46,355 INFO 2024-06-22 07:30:46,355 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-22 07:30:46,356 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:46,676 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:46,676 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:46,676 DEBUG --- stdout --- 2024-06-22 07:30:46,676 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:46,676 DEBUG --- stderr --- 2024-06-22 07:30:46,676 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 07:30:46,677 INFO 2024-06-22 07:30:46,677 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-22 07:30:46,677 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:46,997 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:46,997 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:46,997 DEBUG --- stdout --- 2024-06-22 07:30:46,997 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:46,997 DEBUG --- stderr --- 2024-06-22 07:30:46,997 DEBUG 2024-06-22 07:30:46,998 INFO 2024-06-22 07:30:46,998 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-200/cmd.txt" ]; then echo "/results/orders/login-tree-200/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-200/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/cmd.txt file found" 2024-06-22 07:30:46,998 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 07:30:47,827 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 07:30:47,827 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:47,827 DEBUG --- stdout --- 2024-06-22 07:30:47,827 DEBUG /results/orders/login-tree-200/cmd.txt file found 2024-06-22 07:30:47,827 DEBUG --- stderr --- 2024-06-22 07:30:47,827 DEBUG 2024-06-22 07:30:47,827 INFO 2024-06-22 07:30:47,827 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-22 07:30:47,827 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:48,149 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:48,150 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:48,150 DEBUG --- stdout --- 2024-06-22 07:30:48,150 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:48,150 DEBUG --- stderr --- 2024-06-22 07:30:48,150 DEBUG 2024-06-22 07:30:48,150 INFO 2024-06-22 07:30:48,150 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-200/cmd.txt 2024-06-22 07:30:48,966 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 07:30:48,966 DEBUG --- stdout --- 2024-06-22 07:30:48,967 DEBUG 4 /results/orders/login-tree-200/cmd.txt 2024-06-22 07:30:48,967 DEBUG --- stderr --- 2024-06-22 07:30:48,967 DEBUG 2024-06-22 07:30:48,967 INFO - Download file (size 4k) 2024-06-22 07:30:48,968 INFO 2024-06-22 07:30:48,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-22 07:30:48,968 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:49,274 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:49,275 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:49,275 DEBUG --- stdout --- 2024-06-22 07:30:49,275 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:49,275 DEBUG --- stderr --- 2024-06-22 07:30:49,275 DEBUG source : /results/orders/login-tree-200/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/cmd.txt 2024-06-22 07:30:49,275 INFO 2024-06-22 07:30:49,275 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-22 07:30:49,275 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:49,579 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:49,580 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:49,580 DEBUG --- stdout --- 2024-06-22 07:30:49,580 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:49,580 DEBUG --- stderr --- 2024-06-22 07:30:49,580 DEBUG 2024-06-22 07:30:49,580 INFO 2024-06-22 07:30:49,580 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-200/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/cmd.txt 2024-06-22 07:30:49,580 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 07:30:50,318 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:50,318 DEBUG --- stdout --- 2024-06-22 07:30:50,318 DEBUG tar: Removing leading `/' from member names 2024-06-22 07:30:50,318 DEBUG --- stderr --- 2024-06-22 07:30:50,318 DEBUG 2024-06-22 07:30:50,319 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/cmd.txt is small (1 lines), printing all lines: 2024-06-22 07:30:50,319 DEBUG ----- output ----- 2024-06-22 07:30:50,319 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-200 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-22 07:30:50,319 INFO ________________________________________________________________________________ [2024-06-22 07:30:50] login-tree-200 show_rc : Display rc order ________________________________________________________________________________ 2024-06-22 07:30:50,319 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/rc.txt is small (1 lines), printing all lines: 2024-06-22 07:30:50,319 DEBUG ----- output ----- 2024-06-22 07:30:50,319 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-22 07:30:50,319 INFO ________________________________________________________________________________ [2024-06-22 07:30:50] login-tree-200 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-22 07:30:50,324 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-200/stdout.txt is 23896 lines): 2024-06-22 07:30:50,324 DEBUG ----- output ----- 2024-06-22 07:30:50,324 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-22 07:30:50,324 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-22 07:30:50,324 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-22 07:30:50,324 INFO 2024-06-22 07:30:50,324 INFO ================================================================================ 2024-06-22 07:30:50,324 INFO 2024-06-22 07:28:24 5s elapsed 2024-06-22 07:30:50,324 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 07:30:50,324 INFO > Global (OK=329 KO=0 ) 2024-06-22 07:30:50,324 INFO > protectLoginInitiate (OK=82 KO=0 ) 2024-06-22 07:30:50,324 INFO > PingOneProtectInitializeCallback (OK=81 KO=0 ) 2024-06-22 07:30:50,324 INFO > restLoginUsernamePasswordCallback (OK=79 KO=0 ) 2024-06-22 07:30:50,324 INFO > PingOneProtectEvaluationCallback (OK=71 KO=0 ) 2024-06-22 07:30:50,324 INFO > restloginProgressiveProfileCallback (OK=16 KO=0 ) 2024-06-22 07:30:50,324 INFO 2024-06-22 07:30:50,324 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 07:30:50,324 INFO [- ] 0% 2024-06-22 07:30:50,324 INFO waiting: 722021 / active: 15 / done: 69 2024-06-22 07:30:50,324 INFO ================================================================================ 2024-06-22 07:30:50,324 INFO 2024-06-22 07:30:50,325 INFO 2024-06-22 07:30:50,325 INFO ================================================================================ 2024-06-22 07:30:50,325 INFO 2024-06-22 07:28:29 10s elapsed 2024-06-22 07:30:50,325 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 07:30:50,325 INFO > Global (OK=1350 KO=0 ) 2024-06-22 07:30:50,325 INFO > protectLoginInitiate (OK=331 KO=0 ) 2024-06-22 07:30:50,325 INFO > PingOneProtectInitializeCallback (OK=328 KO=0 ) 2024-06-22 07:30:50,325 INFO > restLoginUsernamePasswordCallback (OK=324 KO=0 ) 2024-06-22 07:30:50,325 INFO > PingOneProtectEvaluationCallback (OK=308 KO=0 ) 2024-06-22 07:30:50,325 INFO > restloginProgressiveProfileCallback (OK=59 KO=0 ) 2024-06-22 07:30:50,325 INFO 2024-06-22 07:30:50,325 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 07:30:50,325 INFO [- ] 0% 2024-06-22 07:30:50,325 INFO waiting: 721771 / active: 28 / done: 306 2024-06-22 07:30:50,325 INFO ================================================================================ 2024-06-22 07:30:50,325 INFO 2024-06-22 07:30:50,325 INFO 2024-06-22 07:30:50,325 INFO ================================================================================ 2024-06-22 07:30:50,325 INFO 2024-06-22 07:28:34 15s elapsed 2024-06-22 07:30:50,325 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 07:30:50,325 INFO > Global (OK=3057 KO=0 ) 2024-06-22 07:30:50,325 INFO > protectLoginInitiate (OK=747 KO=0 ) 2024-06-22 07:30:50,325 INFO > PingOneProtectInitializeCallback (OK=742 KO=0 ) 2024-06-22 07:30:50,325 INFO > restLoginUsernamePasswordCallback (OK=737 KO=0 ) 2024-06-22 07:30:50,325 INFO > PingOneProtectEvaluationCallback (OK=711 KO=0 ) 2024-06-22 07:30:50,325 INFO > restloginProgressiveProfileCallback (OK=120 KO=0 ) 2024-06-22 07:30:50,325 INFO 2024-06-22 07:30:50,325 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 07:30:50,325 INFO [- ] 0% 2024-06-22 07:30:50,325 INFO waiting: 721353 / active: 46 / done: 706 2024-06-22 07:30:50,325 INFO ================================================================================ 2024-06-22 07:30:50,325 INFO 2024-06-22 07:30:50,325 INFO 2024-06-22 07:30:50,325 INFO ================================================================================ 2024-06-22 07:30:50,325 INFO 2024-06-22 07:28:39 20s elapsed 2024-06-22 07:30:50,325 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 07:30:50,325 INFO > Global (OK=5462 KO=0 ) 2024-06-22 07:30:50,325 INFO > protectLoginInitiate (OK=1328 KO=0 ) 2024-06-22 07:30:50,325 INFO > PingOneProtectInitializeCallback (OK=1323 KO=0 ) 2024-06-22 07:30:50,325 INFO > restLoginUsernamePasswordCallback (OK=1314 KO=0 ) 2024-06-22 07:30:50,326 INFO > PingOneProtectEvaluationCallback (OK=1280 KO=0 ) 2024-06-22 07:30:50,326 INFO > restloginProgressiveProfileCallback (OK=217 KO=0 ) 2024-06-22 07:30:50,326 INFO 2024-06-22 07:30:50,326 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 07:30:50,326 INFO [- ] 0% 2024-06-22 07:30:50,326 INFO waiting: 720770 / active: 61 / done: 1274 2024-06-22 07:30:50,326 INFO ================================================================================ 2024-06-22 07:30:50,326 INFO 2024-06-22 07:30:50,326 INFO 2024-06-22 07:30:50,326 INFO ================================================================================ 2024-06-22 07:30:50,326 INFO 2024-06-22 07:28:44 25s elapsed 2024-06-22 07:30:50,326 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 07:30:50,326 INFO > Global (OK=8554 KO=0 ) 2024-06-22 07:30:50,326 INFO > protectLoginInitiate (OK=2077 KO=0 ) 2024-06-22 07:30:50,326 INFO > PingOneProtectInitializeCallback (OK=2069 KO=0 ) 2024-06-22 07:30:50,326 INFO > restLoginUsernamePasswordCallback (OK=2059 KO=0 ) 2024-06-22 07:30:50,326 INFO > PingOneProtectEvaluationCallback (OK=2014 KO=0 ) 2024-06-22 07:30:50,326 INFO > restloginProgressiveProfileCallback (OK=335 KO=0 ) 2024-06-22 07:30:50,326 INFO 2024-06-22 07:30:50,326 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 07:30:50,326 INFO [- ] 0% 2024-06-22 07:30:50,326 INFO waiting: 720019 / active: 77 / done: 2009 2024-06-22 07:30:50,326 INFO ================================================================================ 2024-06-22 07:30:50,326 INFO 2024-06-22 07:30:50,326 INFO 2024-06-22 07:30:50,326 INFO ================================================================================ 2024-06-22 07:30:50,326 INFO 2024-06-22 07:28:49 30s elapsed 2024-06-22 07:30:50,326 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 07:30:50,326 INFO > Global (OK=12348 KO=0 ) 2024-06-22 07:30:50,326 INFO > protectLoginInitiate (OK=2993 KO=0 ) 2024-06-22 07:30:50,326 INFO > PingOneProtectInitializeCallback (OK=2982 KO=0 ) 2024-06-22 07:30:50,326 INFO > restLoginUsernamePasswordCallback (OK=2967 KO=0 ) 2024-06-22 07:30:50,326 INFO > PingOneProtectEvaluationCallback (OK=2916 KO=0 ) 2024-06-22 07:30:50,326 INFO > restloginProgressiveProfileCallback (OK=490 KO=0 ) 2024-06-22 07:30:50,326 INFO 2024-06-22 07:30:50,326 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 07:30:50,326 INFO [- ] 0% 2024-06-22 07:30:50,326 INFO waiting: 719100 / active: 94 / done: 2911 2024-06-22 07:30:50,326 INFO ================================================================================ 2024-06-22 07:30:50,327 INFO 2024-06-22 07:30:50,327 INFO 2024-06-22 07:30:50,327 INFO [...] 2024-06-22 07:30:50,333 INFO > PingOneProtectInitializeCallback (OK=721103 KO=6 ) 2024-06-22 07:30:50,333 INFO > restLoginUsernamePasswordCallback (OK=721082 KO=7 ) 2024-06-22 07:30:50,333 INFO > PingOneProtectEvaluationCallback (OK=720948 KO=38 ) 2024-06-22 07:30:50,333 INFO > restloginProgressiveProfileCallback (OK=162471 KO=45 ) 2024-06-22 07:30:50,333 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 07:30:50,333 INFO > jsonPath($.tokenId).find.exists, found nothing 44 (39.64%) 2024-06-22 07:30:50,333 INFO > status.find.is(200), but actually found 401 34 (30.63%) 2024-06-22 07:30:50,333 INFO > status.find.is(200), but actually found 502 19 (17.12%) 2024-06-22 07:30:50,333 INFO > PingOneProtectInitializeCallback: Failed to build request: No 3 ( 2.70%) 2024-06-22 07:30:50,333 INFO attribute named 'callbacks' is defined 2024-06-22 07:30:50,334 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 3 ( 2.70%) 2024-06-22 07:30:50,334 INFO attribute named 'callbacks' is defined 2024-06-22 07:30:50,334 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 3 ( 2.70%) 2024-06-22 07:30:50,334 INFO attribute named 'callbacks' is defined 2024-06-22 07:30:50,334 INFO > restloginProgressiveProfileCallback: Failed to build request: 3 ( 2.70%) 2024-06-22 07:30:50,334 INFO No attribute named 'callbacks' is defined 2024-06-22 07:30:50,334 INFO > status.find.is(200), but actually found 503 2 ( 1.80%) 2024-06-22 07:30:50,334 INFO 2024-06-22 07:30:50,334 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 07:30:50,334 INFO [#########################################################################-] 99% 2024-06-22 07:30:50,334 INFO waiting: 970 / active: 158 / done: 720977 2024-06-22 07:30:50,334 INFO ================================================================================ 2024-06-22 07:30:50,334 INFO 2024-06-22 07:30:50,334 INFO 2024-06-22 07:30:50,334 INFO ================================================================================ 2024-06-22 07:30:50,334 INFO 2024-06-22 08:28:49 3630s elapsed 2024-06-22 07:30:50,334 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 07:30:50,334 INFO > Global (OK=3050934 KO=99 ) 2024-06-22 07:30:50,334 INFO > protectLoginInitiate (OK=722093 KO=3 ) 2024-06-22 07:30:50,334 INFO > PingOneProtectInitializeCallback (OK=722074 KO=6 ) 2024-06-22 07:30:50,334 INFO > restLoginUsernamePasswordCallback (OK=722057 KO=7 ) 2024-06-22 07:30:50,334 INFO > PingOneProtectEvaluationCallback (OK=721965 KO=38 ) 2024-06-22 07:30:50,334 INFO > restloginProgressiveProfileCallback (OK=162745 KO=45 ) 2024-06-22 07:30:50,334 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 07:30:50,334 INFO > jsonPath($.tokenId).find.exists, found nothing 44 (39.64%) 2024-06-22 07:30:50,334 INFO > status.find.is(200), but actually found 401 34 (30.63%) 2024-06-22 07:30:50,334 INFO > status.find.is(200), but actually found 502 19 (17.12%) 2024-06-22 07:30:50,334 INFO > PingOneProtectInitializeCallback: Failed to build request: No 3 ( 2.70%) 2024-06-22 07:30:50,334 INFO attribute named 'callbacks' is defined 2024-06-22 07:30:50,334 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 3 ( 2.70%) 2024-06-22 07:30:50,334 INFO attribute named 'callbacks' is defined 2024-06-22 07:30:50,334 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 3 ( 2.70%) 2024-06-22 07:30:50,334 INFO attribute named 'callbacks' is defined 2024-06-22 07:30:50,334 INFO > restloginProgressiveProfileCallback: Failed to build request: 3 ( 2.70%) 2024-06-22 07:30:50,334 INFO No attribute named 'callbacks' is defined 2024-06-22 07:30:50,334 INFO > status.find.is(200), but actually found 503 2 ( 1.80%) 2024-06-22 07:30:50,335 INFO 2024-06-22 07:30:50,335 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 07:30:50,335 INFO [#########################################################################-] 99% 2024-06-22 07:30:50,335 INFO waiting: 0 / active: 107 / done: 721998 2024-06-22 07:30:50,335 INFO ================================================================================ 2024-06-22 07:30:50,335 INFO 2024-06-22 07:30:50,335 INFO 2024-06-22 07:30:50,335 INFO ================================================================================ 2024-06-22 07:30:50,335 INFO 2024-06-22 08:28:50 3630s elapsed 2024-06-22 07:30:50,335 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 07:30:50,335 INFO > Global (OK=3051133 KO=99 ) 2024-06-22 07:30:50,335 INFO > protectLoginInitiate (OK=722102 KO=3 ) 2024-06-22 07:30:50,335 INFO > PingOneProtectInitializeCallback (OK=722096 KO=6 ) 2024-06-22 07:30:50,335 INFO > restLoginUsernamePasswordCallback (OK=722095 KO=7 ) 2024-06-22 07:30:50,335 INFO > PingOneProtectEvaluationCallback (OK=722064 KO=38 ) 2024-06-22 07:30:50,335 INFO > restloginProgressiveProfileCallback (OK=162776 KO=45 ) 2024-06-22 07:30:50,335 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 07:30:50,335 INFO > jsonPath($.tokenId).find.exists, found nothing 44 (39.64%) 2024-06-22 07:30:50,335 INFO > status.find.is(200), but actually found 401 34 (30.63%) 2024-06-22 07:30:50,335 INFO > status.find.is(200), but actually found 502 19 (17.12%) 2024-06-22 07:30:50,335 INFO > PingOneProtectInitializeCallback: Failed to build request: No 3 ( 2.70%) 2024-06-22 07:30:50,335 INFO attribute named 'callbacks' is defined 2024-06-22 07:30:50,335 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 3 ( 2.70%) 2024-06-22 07:30:50,335 INFO attribute named 'callbacks' is defined 2024-06-22 07:30:50,335 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 3 ( 2.70%) 2024-06-22 07:30:50,335 INFO attribute named 'callbacks' is defined 2024-06-22 07:30:50,335 INFO > restloginProgressiveProfileCallback: Failed to build request: 3 ( 2.70%) 2024-06-22 07:30:50,335 INFO No attribute named 'callbacks' is defined 2024-06-22 07:30:50,335 INFO > status.find.is(200), but actually found 503 2 ( 1.80%) 2024-06-22 07:30:50,335 INFO 2024-06-22 07:30:50,335 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 07:30:50,335 INFO [##########################################################################]100% 2024-06-22 07:30:50,335 INFO waiting: 0 / active: 0 / done: 722105 2024-06-22 07:30:50,335 INFO ================================================================================ 2024-06-22 07:30:50,335 INFO 2024-06-22 07:30:50,335 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-22 07:30:50,335 INFO 2024-06-22 07:30:50,335 INFO ---------------------- 2024-06-22 07:30:50,335 INFO [22/Jun/2024 08:28:50] Create tar file login-tree-200.tar.gz 2024-06-22 07:30:50,335 INFO ---------------------- 2024-06-22 07:30:50,335 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-22 07:30:50,335 INFO RESULT_FOLDER=login-tree-200 2024-06-22 07:30:50,336 INFO SIMULATION_PATH=login-tree-200/loadtest-20240622062819154 2024-06-22 07:30:50,336 INFO PWD=/results/gatling/login-tree-200/loadtest-20240622062819154 2024-06-22 07:30:50,336 INFO [cmd] tar -czvf /results/gatling//login-tree-200.tar.gz --exclude=simulation.log * 2024-06-22 07:30:50,336 INFO /results/gatling 2024-06-22 07:30:50,336 INFO 2024-06-22 07:30:50,336 INFO ---------------------- 2024-06-22 07:30:50,336 INFO [22/Jun/2024 08:28:50] Update /results/gatling//latest 2024-06-22 07:30:50,336 INFO ---------------------- 2024-06-22 07:30:50,336 INFO 2024-06-22 07:30:50,336 INFO ---------------------- 2024-06-22 07:30:50,336 INFO [22/Jun/2024 08:28:50] Update /results/gatling//latest.tar.gz 2024-06-22 07:30:50,336 INFO ---------------------- 2024-06-22 07:30:50,336 INFO ________________________________________________________________________________ [2024-06-22 07:30:50] login-tree-200 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-22 07:30:50,336 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stderr.txt is small (0 lines), printing all lines: 2024-06-22 07:30:50,336 DEBUG ----- output ----- 2024-06-22 07:30:50,336 DEBUG 2024-06-22 07:30:50,336 INFO ________________________________________________________________________________ [2024-06-22 07:30:50] login-tree-200 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-22 07:30:50] login-tree-200 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-22 07:30:50] login-tree-200 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped