--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-22 05:25:34] login-tree-150 pre : N/A ________________________________________________________________________________ 2024-06-22 05:25:34 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-22 05:25: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-22 05:25:34,545 INFO 2024-06-22 05:25:34,545 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 05:25:34,545 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:34,862 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:34,863 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:34,863 DEBUG --- stdout --- 2024-06-22 05:25:34,863 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:34,863 DEBUG --- stderr --- 2024-06-22 05:25:34,863 DEBUG ________________________________________________________________________________ [2024-06-22 05:25:34] login-tree-150 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-22 05:25:34,864 INFO 2024-06-22 05:25:34,864 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 05:25:34,864 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:35,176 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:35,177 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:35,177 DEBUG --- stdout --- 2024-06-22 05:25:35,177 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:35,177 DEBUG --- stderr --- 2024-06-22 05:25:35,177 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-22 05:25:35] login-tree-150 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-22 05:25:35,178 INFO 2024-06-22 05:25:35,178 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 05:25:35,178 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:35,492 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:35,493 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:35,493 DEBUG --- stdout --- 2024-06-22 05:25:35,493 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:35,493 DEBUG --- stderr --- 2024-06-22 05:25:35,493 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-22 05:25: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-22 05:25:35,496 INFO 2024-06-22 05:25:35,496 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-22 05:25:35,496 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 05:25:35,747 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 05:25:35,747 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:35,748 DEBUG --- stdout --- 2024-06-22 05:25:35,748 DEBUG WAITING 2024-06-22 05:25:35,748 DEBUG --- stderr --- 2024-06-22 05:25:35,748 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 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-06-22 05:25:35] login-tree-150 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-22 05:25:35,749 INFO 2024-06-22 05:25:35,749 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 05:25:35,749 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:36,070 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:36,071 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:36,071 DEBUG --- stdout --- 2024-06-22 05:25:36,071 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:36,071 DEBUG --- stderr --- 2024-06-22 05:25:36,071 DEBUG 2024-06-22 05:25:36,071 INFO 2024-06-22 05:25:36,071 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-22 05:25:36,071 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 05:25:37,508 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:37,508 DEBUG --- stdout --- 2024-06-22 05:25:37,508 DEBUG 2024-06-22 05:25:37,508 DEBUG --- stderr --- 2024-06-22 05:25:37,508 DEBUG Order has been successfully forwarded to overseer 2024-06-22 05:25:37 - INFO: Check if Gatling simulation started successfully 2024-06-22 05:25:37,509 INFO 2024-06-22 05:25:37,509 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 05:25:37,509 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:37,859 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:37,860 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:37,860 DEBUG --- stdout --- 2024-06-22 05:25:37,860 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:37,860 DEBUG --- stderr --- 2024-06-22 05:25:37,860 DEBUG 2024-06-22 05:25:37,860 INFO 2024-06-22 05:25:37,860 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-22 05:25:37,860 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 05:25:38,646 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-22 05:25:44,382 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-22 05:25:44,382 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:44,382 DEBUG --- stdout --- 2024-06-22 05:25:44,382 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 05:25:44,382 DEBUG --- stderr --- 2024-06-22 05:25:44,382 DEBUG ________________________________________________________________________________ [2024-06-22 05:25: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 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 05s, 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 06:27:54] login-tree-150 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-22 06:27:54,597 INFO 2024-06-22 06:27:54,598 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:27:54,598 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:27:54,933 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:27:54,934 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:27:54,934 DEBUG --- stdout --- 2024-06-22 06:27:54,934 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:27:54,934 DEBUG --- stderr --- 2024-06-22 06:27:54,934 DEBUG 2024-06-22 06:27:54,934 INFO 2024-06-22 06:27:54,934 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 06:27:54,934 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 06:27:55,720 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:27:55,721 DEBUG --- stdout --- 2024-06-22 06:27:55,721 DEBUG 2024-06-22 06:27:55,721 DEBUG --- stderr --- 2024-06-22 06:27:55,721 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-22 06:27:55] login-tree-150 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-22 06:27:55 - 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-22 06:27:55,723 INFO 2024-06-22 06:27:55,724 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:27:55,724 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:27:56,048 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:27:56,048 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:27:56,048 DEBUG --- stdout --- 2024-06-22 06:27:56,048 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:27:56,048 DEBUG --- stderr --- 2024-06-22 06:27:56,048 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 06:27:56,049 INFO 2024-06-22 06:27:56,049 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:27:56,049 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:27:56,373 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:27:56,373 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:27:56,373 DEBUG --- stdout --- 2024-06-22 06:27:56,373 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:27:56,373 DEBUG --- stderr --- 2024-06-22 06:27:56,374 DEBUG 2024-06-22 06:27:56,374 INFO 2024-06-22 06:27:56,374 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-22 06:27:56,374 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 06:27:57,191 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 06:27:57,191 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:27:57,191 DEBUG --- stdout --- 2024-06-22 06:27:57,191 DEBUG /results/orders/login-tree-150/rc.txt file found 2024-06-22 06:27:57,191 DEBUG --- stderr --- 2024-06-22 06:27:57,192 DEBUG 2024-06-22 06:27:57,192 INFO 2024-06-22 06:27:57,192 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:27:57,192 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:27:57,512 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:27:57,512 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:27:57,512 DEBUG --- stdout --- 2024-06-22 06:27:57,512 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:27:57,512 DEBUG --- stderr --- 2024-06-22 06:27:57,512 DEBUG 2024-06-22 06:27:57,513 INFO 2024-06-22 06:27:57,513 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-22 06:27:58,317 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 06:27:58,317 DEBUG --- stdout --- 2024-06-22 06:27:58,317 DEBUG 4 /results/orders/login-tree-150/rc.txt 2024-06-22 06:27:58,317 DEBUG --- stderr --- 2024-06-22 06:27:58,317 DEBUG 2024-06-22 06:27:58,317 INFO - Download file (size 4k) 2024-06-22 06:27:58,318 INFO 2024-06-22 06:27:58,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 06:27:58,318 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:27:58,627 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:27:58,627 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:27:58,627 DEBUG --- stdout --- 2024-06-22 06:27:58,627 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:27:58,627 DEBUG --- stderr --- 2024-06-22 06:27:58,627 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-22 06:27:58,628 INFO 2024-06-22 06:27:58,628 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:27:58,628 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:27:58,944 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:27:58,945 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:27:58,945 DEBUG --- stdout --- 2024-06-22 06:27:58,945 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:27:58,945 DEBUG --- stderr --- 2024-06-22 06:27:58,945 DEBUG 2024-06-22 06:27:58,945 INFO 2024-06-22 06:27:58,945 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-22 06:27:58,945 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 06:27:59,717 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:27:59,717 DEBUG --- stdout --- 2024-06-22 06:27:59,717 DEBUG tar: Removing leading `/' from member names 2024-06-22 06:27:59,717 DEBUG --- stderr --- 2024-06-22 06:27:59,717 DEBUG 2024-06-22 06:27:59,718 INFO 2024-06-22 06:27:59,718 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:27:59,718 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:00,030 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:00,031 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:00,031 DEBUG --- stdout --- 2024-06-22 06:28:00,031 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:00,031 DEBUG --- stderr --- 2024-06-22 06:28:00,031 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 06:28:00,031 INFO 2024-06-22 06:28:00,031 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:00,031 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:00,339 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:00,339 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:00,339 DEBUG --- stdout --- 2024-06-22 06:28:00,339 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:00,339 DEBUG --- stderr --- 2024-06-22 06:28:00,339 DEBUG 2024-06-22 06:28:00,340 INFO 2024-06-22 06:28:00,340 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-22 06:28:00,340 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 06:28:01,148 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 06:28:01,149 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:01,149 DEBUG --- stdout --- 2024-06-22 06:28:01,149 DEBUG /results/orders/login-tree-150/stderr.txt file found 2024-06-22 06:28:01,149 DEBUG --- stderr --- 2024-06-22 06:28:01,149 DEBUG 2024-06-22 06:28:01,149 INFO 2024-06-22 06:28:01,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:01,149 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:01,452 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:01,452 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:01,452 DEBUG --- stdout --- 2024-06-22 06:28:01,453 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:01,453 DEBUG --- stderr --- 2024-06-22 06:28:01,453 DEBUG 2024-06-22 06:28:01,453 INFO 2024-06-22 06:28:01,453 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-22 06:28:02,260 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 06:28:02,260 DEBUG --- stdout --- 2024-06-22 06:28:02,260 DEBUG 0 /results/orders/login-tree-150/stderr.txt 2024-06-22 06:28:02,260 DEBUG --- stderr --- 2024-06-22 06:28:02,260 DEBUG 2024-06-22 06:28:02,260 INFO - Download file (size 0k) 2024-06-22 06:28:02,261 INFO 2024-06-22 06:28:02,261 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:02,261 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:02,603 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:02,603 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:02,603 DEBUG --- stdout --- 2024-06-22 06:28:02,603 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:02,603 DEBUG --- stderr --- 2024-06-22 06:28:02,603 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-22 06:28:02,604 INFO 2024-06-22 06:28:02,604 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:02,604 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:02,934 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:02,934 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:02,934 DEBUG --- stdout --- 2024-06-22 06:28:02,934 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:02,934 DEBUG --- stderr --- 2024-06-22 06:28:02,934 DEBUG 2024-06-22 06:28:02,935 INFO 2024-06-22 06:28:02,935 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-22 06:28:02,935 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 06:28:03,686 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:03,687 DEBUG --- stdout --- 2024-06-22 06:28:03,687 DEBUG tar: Removing leading `/' from member names 2024-06-22 06:28:03,687 DEBUG --- stderr --- 2024-06-22 06:28:03,687 DEBUG 2024-06-22 06:28:03,687 INFO 2024-06-22 06:28:03,687 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:03,688 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:04,003 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:04,003 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:04,003 DEBUG --- stdout --- 2024-06-22 06:28:04,003 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:04,003 DEBUG --- stderr --- 2024-06-22 06:28:04,003 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 06:28:04,004 INFO 2024-06-22 06:28:04,004 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:04,004 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:04,323 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:04,323 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:04,323 DEBUG --- stdout --- 2024-06-22 06:28:04,323 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:04,323 DEBUG --- stderr --- 2024-06-22 06:28:04,323 DEBUG 2024-06-22 06:28:04,324 INFO 2024-06-22 06:28:04,324 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-22 06:28:04,324 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 06:28:05,145 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 06:28:05,145 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:05,145 DEBUG --- stdout --- 2024-06-22 06:28:05,145 DEBUG /results/orders/login-tree-150/stdout.txt file found 2024-06-22 06:28:05,145 DEBUG --- stderr --- 2024-06-22 06:28:05,145 DEBUG 2024-06-22 06:28:05,145 INFO 2024-06-22 06:28:05,145 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:05,145 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:05,465 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:05,465 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:05,465 DEBUG --- stdout --- 2024-06-22 06:28:05,465 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:05,465 DEBUG --- stderr --- 2024-06-22 06:28:05,465 DEBUG 2024-06-22 06:28:05,465 INFO 2024-06-22 06:28:05,466 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-22 06:28:06,289 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 06:28:06,289 DEBUG --- stdout --- 2024-06-22 06:28:06,291 DEBUG 1476 /results/orders/login-tree-150/stdout.txt 2024-06-22 06:28:06,291 DEBUG --- stderr --- 2024-06-22 06:28:06,291 DEBUG 2024-06-22 06:28:06,291 INFO - Download file (size 1476k) 2024-06-22 06:28:06,292 INFO 2024-06-22 06:28:06,292 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:06,292 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:06,612 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:06,613 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:06,613 DEBUG --- stdout --- 2024-06-22 06:28:06,613 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:06,613 DEBUG --- stderr --- 2024-06-22 06:28:06,613 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-22 06:28:06,613 INFO 2024-06-22 06:28:06,613 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:06,613 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:06,931 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:06,931 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:06,931 DEBUG --- stdout --- 2024-06-22 06:28:06,931 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:06,931 DEBUG --- stderr --- 2024-06-22 06:28:06,931 DEBUG 2024-06-22 06:28:06,932 INFO 2024-06-22 06:28:06,932 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-22 06:28:06,932 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 06:28:08,156 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:08,156 DEBUG --- stdout --- 2024-06-22 06:28:08,156 DEBUG tar: Removing leading `/' from member names 2024-06-22 06:28:08,156 DEBUG --- stderr --- 2024-06-22 06:28:08,156 DEBUG ________________________________________________________________________________ [2024-06-22 06:28:08] login-tree-150 show_cmd : Display order command ________________________________________________________________________________ 2024-06-22 06:28:08,157 INFO 2024-06-22 06:28:08,157 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:08,157 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:08,475 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:08,475 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:08,475 DEBUG --- stdout --- 2024-06-22 06:28:08,475 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:08,475 DEBUG --- stderr --- 2024-06-22 06:28:08,475 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 06:28:08,476 INFO 2024-06-22 06:28:08,476 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:08,476 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:08,796 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:08,797 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:08,797 DEBUG --- stdout --- 2024-06-22 06:28:08,797 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:08,797 DEBUG --- stderr --- 2024-06-22 06:28:08,797 DEBUG 2024-06-22 06:28:08,797 INFO 2024-06-22 06:28:08,797 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-22 06:28:08,797 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 06:28:09,599 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 06:28:09,599 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:09,599 DEBUG --- stdout --- 2024-06-22 06:28:09,599 DEBUG /results/orders/login-tree-150/cmd.txt file found 2024-06-22 06:28:09,599 DEBUG --- stderr --- 2024-06-22 06:28:09,599 DEBUG 2024-06-22 06:28:09,599 INFO 2024-06-22 06:28:09,599 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:09,599 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:09,905 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:09,905 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:09,905 DEBUG --- stdout --- 2024-06-22 06:28:09,905 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:09,905 DEBUG --- stderr --- 2024-06-22 06:28:09,905 DEBUG 2024-06-22 06:28:09,905 INFO 2024-06-22 06:28:09,905 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-22 06:28:10,712 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 06:28:10,712 DEBUG --- stdout --- 2024-06-22 06:28:10,712 DEBUG 4 /results/orders/login-tree-150/cmd.txt 2024-06-22 06:28:10,712 DEBUG --- stderr --- 2024-06-22 06:28:10,712 DEBUG 2024-06-22 06:28:10,712 INFO - Download file (size 4k) 2024-06-22 06:28:10,713 INFO 2024-06-22 06:28:10,713 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:10,713 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:11,020 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:11,021 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:11,021 DEBUG --- stdout --- 2024-06-22 06:28:11,021 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:11,021 DEBUG --- stderr --- 2024-06-22 06:28:11,021 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-22 06:28:11,021 INFO 2024-06-22 06:28:11,021 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:11,021 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 06:28:11,339 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 06:28:11,340 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:11,341 DEBUG --- stdout --- 2024-06-22 06:28:11,342 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 06:28:11,342 DEBUG --- stderr --- 2024-06-22 06:28:11,342 DEBUG 2024-06-22 06:28:11,342 INFO 2024-06-22 06:28:11,342 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-22 06:28:11,343 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 06:28:12,088 INFO [loop_until]: OK (rc = 0) 2024-06-22 06:28:12,088 DEBUG --- stdout --- 2024-06-22 06:28:12,089 DEBUG tar: Removing leading `/' from member names 2024-06-22 06:28:12,089 DEBUG --- stderr --- 2024-06-22 06:28:12,089 DEBUG 2024-06-22 06:28:12,089 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-22 06:28:12,089 DEBUG ----- output ----- 2024-06-22 06:28:12,089 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-22 06:28:12,089 INFO ________________________________________________________________________________ [2024-06-22 06:28:12] login-tree-150 show_rc : Display rc order ________________________________________________________________________________ 2024-06-22 06:28:12,090 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-22 06:28:12,090 DEBUG ----- output ----- 2024-06-22 06:28:12,090 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-22 06:28:12,090 INFO ________________________________________________________________________________ [2024-06-22 06:28:12] login-tree-150 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-22 06:28:12,093 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 18811 lines): 2024-06-22 06:28:12,094 DEBUG ----- output ----- 2024-06-22 06:28:12,094 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-22 06:28:12,094 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-22 06:28:12,094 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-22 06:28:12,094 INFO 2024-06-22 06:28:12,094 INFO ================================================================================ 2024-06-22 06:28:12,094 INFO 2024-06-22 06:25:46 5s elapsed 2024-06-22 06:28:12,094 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 06:28:12,094 INFO > Global (OK=235 KO=0 ) 2024-06-22 06:28:12,094 INFO > protectLoginInitiate (OK=62 KO=0 ) 2024-06-22 06:28:12,094 INFO > PingOneProtectInitializeCallback (OK=60 KO=0 ) 2024-06-22 06:28:12,094 INFO > restLoginUsernamePasswordCallback (OK=59 KO=0 ) 2024-06-22 06:28:12,094 INFO > PingOneProtectEvaluationCallback (OK=53 KO=0 ) 2024-06-22 06:28:12,094 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-22 06:28:12,094 INFO 2024-06-22 06:28:12,094 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 06:28:12,094 INFO [- ] 0% 2024-06-22 06:28:12,094 INFO waiting: 542267 / active: 10 / done: 53 2024-06-22 06:28:12,094 INFO ================================================================================ 2024-06-22 06:28:12,094 INFO 2024-06-22 06:28:12,094 INFO 2024-06-22 06:28:12,094 INFO ================================================================================ 2024-06-22 06:28:12,094 INFO 2024-06-22 06:25:51 10s elapsed 2024-06-22 06:28:12,094 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 06:28:12,094 INFO > Global (OK=977 KO=0 ) 2024-06-22 06:28:12,094 INFO > protectLoginInitiate (OK=249 KO=0 ) 2024-06-22 06:28:12,094 INFO > PingOneProtectInitializeCallback (OK=246 KO=0 ) 2024-06-22 06:28:12,094 INFO > restLoginUsernamePasswordCallback (OK=244 KO=0 ) 2024-06-22 06:28:12,094 INFO > PingOneProtectEvaluationCallback (OK=231 KO=0 ) 2024-06-22 06:28:12,094 INFO > restloginProgressiveProfileCallback (OK=7 KO=0 ) 2024-06-22 06:28:12,094 INFO 2024-06-22 06:28:12,094 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 06:28:12,094 INFO [- ] 0% 2024-06-22 06:28:12,094 INFO waiting: 542079 / active: 21 / done: 230 2024-06-22 06:28:12,094 INFO ================================================================================ 2024-06-22 06:28:12,095 INFO 2024-06-22 06:28:12,095 INFO 2024-06-22 06:28:12,095 INFO ================================================================================ 2024-06-22 06:28:12,095 INFO 2024-06-22 06:25:56 15s elapsed 2024-06-22 06:28:12,095 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 06:28:12,095 INFO > Global (OK=2230 KO=0 ) 2024-06-22 06:28:12,095 INFO > protectLoginInitiate (OK=560 KO=0 ) 2024-06-22 06:28:12,095 INFO > PingOneProtectInitializeCallback (OK=557 KO=0 ) 2024-06-22 06:28:12,095 INFO > restLoginUsernamePasswordCallback (OK=553 KO=0 ) 2024-06-22 06:28:12,095 INFO > PingOneProtectEvaluationCallback (OK=535 KO=0 ) 2024-06-22 06:28:12,095 INFO > restloginProgressiveProfileCallback (OK=25 KO=0 ) 2024-06-22 06:28:12,095 INFO 2024-06-22 06:28:12,095 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 06:28:12,095 INFO [- ] 0% 2024-06-22 06:28:12,095 INFO waiting: 541766 / active: 29 / done: 535 2024-06-22 06:28:12,095 INFO ================================================================================ 2024-06-22 06:28:12,095 INFO 2024-06-22 06:28:12,095 INFO 2024-06-22 06:28:12,095 INFO ================================================================================ 2024-06-22 06:28:12,095 INFO 2024-06-22 06:26:01 20s elapsed 2024-06-22 06:28:12,095 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 06:28:12,095 INFO > Global (OK=3946 KO=0 ) 2024-06-22 06:28:12,095 INFO > protectLoginInitiate (OK=997 KO=0 ) 2024-06-22 06:28:12,095 INFO > PingOneProtectInitializeCallback (OK=993 KO=0 ) 2024-06-22 06:28:12,095 INFO > restLoginUsernamePasswordCallback (OK=988 KO=0 ) 2024-06-22 06:28:12,095 INFO > PingOneProtectEvaluationCallback (OK=923 KO=0 ) 2024-06-22 06:28:12,095 INFO > restloginProgressiveProfileCallback (OK=45 KO=0 ) 2024-06-22 06:28:12,095 INFO 2024-06-22 06:28:12,095 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 06:28:12,095 INFO [- ] 0% 2024-06-22 06:28:12,095 INFO waiting: 541328 / active: 79 / done: 923 2024-06-22 06:28:12,095 INFO ================================================================================ 2024-06-22 06:28:12,095 INFO 2024-06-22 06:28:12,095 INFO 2024-06-22 06:28:12,095 INFO ================================================================================ 2024-06-22 06:28:12,095 INFO 2024-06-22 06:26:06 25s elapsed 2024-06-22 06:28:12,095 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 06:28:12,095 INFO > Global (OK=6241 KO=0 ) 2024-06-22 06:28:12,095 INFO > protectLoginInitiate (OK=1558 KO=0 ) 2024-06-22 06:28:12,095 INFO > PingOneProtectInitializeCallback (OK=1553 KO=0 ) 2024-06-22 06:28:12,096 INFO > restLoginUsernamePasswordCallback (OK=1545 KO=0 ) 2024-06-22 06:28:12,096 INFO > PingOneProtectEvaluationCallback (OK=1512 KO=0 ) 2024-06-22 06:28:12,096 INFO > restloginProgressiveProfileCallback (OK=73 KO=0 ) 2024-06-22 06:28:12,096 INFO 2024-06-22 06:28:12,096 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 06:28:12,096 INFO [- ] 0% 2024-06-22 06:28:12,096 INFO waiting: 540766 / active: 53 / done: 1511 2024-06-22 06:28:12,096 INFO ================================================================================ 2024-06-22 06:28:12,096 INFO 2024-06-22 06:28:12,096 INFO 2024-06-22 06:28:12,096 INFO ================================================================================ 2024-06-22 06:28:12,096 INFO 2024-06-22 06:26:11 30s elapsed 2024-06-22 06:28:12,096 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 06:28:12,096 INFO > Global (OK=9011 KO=0 ) 2024-06-22 06:28:12,096 INFO > protectLoginInitiate (OK=2245 KO=0 ) 2024-06-22 06:28:12,096 INFO > PingOneProtectInitializeCallback (OK=2238 KO=0 ) 2024-06-22 06:28:12,096 INFO > restLoginUsernamePasswordCallback (OK=2229 KO=0 ) 2024-06-22 06:28:12,096 INFO > PingOneProtectEvaluationCallback (OK=2188 KO=0 ) 2024-06-22 06:28:12,096 INFO > restloginProgressiveProfileCallback (OK=111 KO=0 ) 2024-06-22 06:28:12,096 INFO 2024-06-22 06:28:12,096 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 06:28:12,096 INFO [- ] 0% 2024-06-22 06:28:12,096 INFO waiting: 540080 / active: 63 / done: 2187 2024-06-22 06:28:12,096 INFO ================================================================================ 2024-06-22 06:28:12,096 INFO 2024-06-22 06:28:12,096 INFO 2024-06-22 06:28:12,096 INFO [...] 2024-06-22 06:28:12,102 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 06:28:12,102 INFO > Global (OK=2228002 KO=71 ) 2024-06-22 06:28:12,102 INFO > protectLoginInitiate (OK=541638 KO=1 ) 2024-06-22 06:28:12,102 INFO > PingOneProtectInitializeCallback (OK=541625 KO=1 ) 2024-06-22 06:28:12,102 INFO > restLoginUsernamePasswordCallback (OK=541609 KO=7 ) 2024-06-22 06:28:12,102 INFO > PingOneProtectEvaluationCallback (OK=541553 KO=31 ) 2024-06-22 06:28:12,102 INFO > restloginProgressiveProfileCallback (OK=61577 KO=31 ) 2024-06-22 06:28:12,102 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 06:28:12,102 INFO > jsonPath($.tokenId).find.exists, found nothing 30 (40.00%) 2024-06-22 06:28:12,102 INFO > status.find.is(200), but actually found 401 29 (38.67%) 2024-06-22 06:28:12,102 INFO > status.find.is(200), but actually found 502 12 (16.00%) 2024-06-22 06:28:12,102 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 ( 1.33%) 2024-06-22 06:28:12,102 INFO attribute named 'callbacks' is defined 2024-06-22 06:28:12,102 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 ( 1.33%) 2024-06-22 06:28:12,102 INFO attribute named 'callbacks' is defined 2024-06-22 06:28:12,102 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 ( 1.33%) 2024-06-22 06:28:12,102 INFO attribute named 'callbacks' is defined 2024-06-22 06:28:12,102 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 ( 1.33%) 2024-06-22 06:28:12,102 INFO No attribute named 'callbacks' is defined 2024-06-22 06:28:12,102 INFO 2024-06-22 06:28:12,102 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 06:28:12,102 INFO [#########################################################################-] 99% 2024-06-22 06:28:12,102 INFO waiting: 686 / active: 63 / done: 541581 2024-06-22 06:28:12,102 INFO ================================================================================ 2024-06-22 06:28:12,102 INFO 2024-06-22 06:28:12,102 INFO 2024-06-22 06:28:12,102 INFO ================================================================================ 2024-06-22 06:28:12,102 INFO 2024-06-22 07:26:11 3630s elapsed 2024-06-22 06:28:12,102 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 06:28:12,102 INFO > Global (OK=2230869 KO=71 ) 2024-06-22 06:28:12,103 INFO > protectLoginInitiate (OK=542323 KO=1 ) 2024-06-22 06:28:12,103 INFO > PingOneProtectInitializeCallback (OK=542315 KO=1 ) 2024-06-22 06:28:12,103 INFO > restLoginUsernamePasswordCallback (OK=542301 KO=7 ) 2024-06-22 06:28:12,103 INFO > PingOneProtectEvaluationCallback (OK=542243 KO=31 ) 2024-06-22 06:28:12,103 INFO > restloginProgressiveProfileCallback (OK=61687 KO=31 ) 2024-06-22 06:28:12,103 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 06:28:12,103 INFO > jsonPath($.tokenId).find.exists, found nothing 30 (40.00%) 2024-06-22 06:28:12,103 INFO > status.find.is(200), but actually found 401 29 (38.67%) 2024-06-22 06:28:12,103 INFO > status.find.is(200), but actually found 502 12 (16.00%) 2024-06-22 06:28:12,103 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 ( 1.33%) 2024-06-22 06:28:12,103 INFO attribute named 'callbacks' is defined 2024-06-22 06:28:12,103 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 ( 1.33%) 2024-06-22 06:28:12,103 INFO attribute named 'callbacks' is defined 2024-06-22 06:28:12,103 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 ( 1.33%) 2024-06-22 06:28:12,103 INFO attribute named 'callbacks' is defined 2024-06-22 06:28:12,103 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 ( 1.33%) 2024-06-22 06:28:12,103 INFO No attribute named 'callbacks' is defined 2024-06-22 06:28:12,103 INFO 2024-06-22 06:28:12,103 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 06:28:12,103 INFO [#########################################################################-] 99% 2024-06-22 06:28:12,103 INFO waiting: 0 / active: 57 / done: 542273 2024-06-22 06:28:12,103 INFO ================================================================================ 2024-06-22 06:28:12,103 INFO 2024-06-22 06:28:12,103 INFO 2024-06-22 06:28:12,103 INFO ================================================================================ 2024-06-22 06:28:12,103 INFO 2024-06-22 07:26:12 3630s elapsed 2024-06-22 06:28:12,103 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 06:28:12,103 INFO > Global (OK=2230982 KO=71 ) 2024-06-22 06:28:12,103 INFO > protectLoginInitiate (OK=542329 KO=1 ) 2024-06-22 06:28:12,103 INFO > PingOneProtectInitializeCallback (OK=542328 KO=1 ) 2024-06-22 06:28:12,103 INFO > restLoginUsernamePasswordCallback (OK=542322 KO=7 ) 2024-06-22 06:28:12,103 INFO > PingOneProtectEvaluationCallback (OK=542298 KO=31 ) 2024-06-22 06:28:12,103 INFO > restloginProgressiveProfileCallback (OK=61705 KO=31 ) 2024-06-22 06:28:12,103 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 06:28:12,103 INFO > jsonPath($.tokenId).find.exists, found nothing 30 (40.00%) 2024-06-22 06:28:12,103 INFO > status.find.is(200), but actually found 401 29 (38.67%) 2024-06-22 06:28:12,103 INFO > status.find.is(200), but actually found 502 12 (16.00%) 2024-06-22 06:28:12,103 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 ( 1.33%) 2024-06-22 06:28:12,103 INFO attribute named 'callbacks' is defined 2024-06-22 06:28:12,103 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 ( 1.33%) 2024-06-22 06:28:12,104 INFO attribute named 'callbacks' is defined 2024-06-22 06:28:12,104 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 ( 1.33%) 2024-06-22 06:28:12,104 INFO attribute named 'callbacks' is defined 2024-06-22 06:28:12,104 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 ( 1.33%) 2024-06-22 06:28:12,104 INFO No attribute named 'callbacks' is defined 2024-06-22 06:28:12,104 INFO 2024-06-22 06:28:12,104 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 06:28:12,104 INFO [##########################################################################]100% 2024-06-22 06:28:12,104 INFO waiting: 0 / active: 0 / done: 542330 2024-06-22 06:28:12,104 INFO ================================================================================ 2024-06-22 06:28:12,104 INFO 2024-06-22 06:28:12,104 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-22 06:28:12,104 INFO 2024-06-22 06:28:12,104 INFO ---------------------- 2024-06-22 06:28:12,104 INFO [22/Jun/2024 07:26:12] Create tar file login-tree-150.tar.gz 2024-06-22 06:28:12,104 INFO ---------------------- 2024-06-22 06:28:12,104 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-22 06:28:12,104 INFO RESULT_FOLDER=login-tree-150 2024-06-22 06:28:12,104 INFO SIMULATION_PATH=login-tree-150/loadtest-20240622052541201 2024-06-22 06:28:12,104 INFO PWD=/results/gatling/login-tree-150/loadtest-20240622052541201 2024-06-22 06:28:12,104 INFO [cmd] tar -czvf /results/gatling//login-tree-150.tar.gz --exclude=simulation.log * 2024-06-22 06:28:12,104 INFO /results/gatling 2024-06-22 06:28:12,104 INFO 2024-06-22 06:28:12,104 INFO ---------------------- 2024-06-22 06:28:12,104 INFO [22/Jun/2024 07:26:12] Update /results/gatling//latest 2024-06-22 06:28:12,104 INFO ---------------------- 2024-06-22 06:28:12,104 INFO 2024-06-22 06:28:12,104 INFO ---------------------- 2024-06-22 06:28:12,104 INFO [22/Jun/2024 07:26:12] Update /results/gatling//latest.tar.gz 2024-06-22 06:28:12,104 INFO ---------------------- 2024-06-22 06:28:12,104 INFO ________________________________________________________________________________ [2024-06-22 06:28:12] login-tree-150 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-22 06:28:12,105 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-22 06:28:12,105 DEBUG ----- output ----- 2024-06-22 06:28:12,105 DEBUG 2024-06-22 06:28:12,105 INFO ________________________________________________________________________________ [2024-06-22 06:28: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-22 06:28: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-22 06:28:12] login-tree-150 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped