--Task-- name: login-tree-250 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['login-tree-200'] preceding_task: login-tree-200 options: {'testCase': 'idc.loginProtect', 'testMode': 'open', 'throughput': 250, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login_Protect'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-22 07:30:51] login-tree-250 pre : N/A ________________________________________________________________________________ 2024-06-22 07:30:51 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-22 07:30:51 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-250/stdout.txt Pod log : /results/orders/login-tree-250 2024-06-22 07:30:51,199 INFO 2024-06-22 07:30:51,199 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:51,199 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:51,504 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:51,504 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:51,504 DEBUG --- stdout --- 2024-06-22 07:30:51,504 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:51,504 DEBUG --- stderr --- 2024-06-22 07:30:51,504 DEBUG ________________________________________________________________________________ [2024-06-22 07:30:51] login-tree-250 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-22 07:30:51,505 INFO 2024-06-22 07:30:51,505 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:51,505 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:51,808 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:51,809 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:51,809 DEBUG --- stdout --- 2024-06-22 07:30:51,809 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:51,809 DEBUG --- stderr --- 2024-06-22 07:30:51,809 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.loginProtect@ema-perf-p1" --results-folder /results/gatling/login-tree-250 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-22 07:30:51] login-tree-250 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-22 07:30:51,810 INFO 2024-06-22 07:30:51,810 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:51,810 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:52,113 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:52,114 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:52,114 DEBUG --- stdout --- 2024-06-22 07:30:52,114 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:52,114 DEBUG --- stderr --- 2024-06-22 07:30:52,114 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/order.json { "name": "login-tree-250", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.loginProtect@ema-perf-p1\" --results-folder /results/gatling/login-tree-250 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-250", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=69cee86761d9c02 -Dlogin_tree=Login_Protect -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.loginProtect -DtestMode=open -Dthroughput=250 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-22 07:30:52] login-tree-250 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-22 07:30:52,117 INFO 2024-06-22 07:30:52,117 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-22 07:30:52,117 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 07:30:52,371 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 07:30:52,372 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:52,372 DEBUG --- stdout --- 2024-06-22 07:30:52,372 DEBUG WAITING 2024-06-22 07:30:52,372 DEBUG --- stderr --- 2024-06-22 07:30:52,372 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 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-06-22 07:30:52] login-tree-250 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-22 07:30:52,373 INFO 2024-06-22 07:30:52,373 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:52,373 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:52,708 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:52,708 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:52,708 DEBUG --- stdout --- 2024-06-22 07:30:52,708 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:52,708 DEBUG --- stderr --- 2024-06-22 07:30:52,708 DEBUG 2024-06-22 07:30:52,709 INFO 2024-06-22 07:30:52,709 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-22 07:30:52,709 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 07:30:54,081 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:54,082 DEBUG --- stdout --- 2024-06-22 07:30:54,082 DEBUG 2024-06-22 07:30:54,082 DEBUG --- stderr --- 2024-06-22 07:30:54,082 DEBUG Order has been successfully forwarded to overseer 2024-06-22 07:30:54 - INFO: Check if Gatling simulation started successfully 2024-06-22 07:30:54,082 INFO 2024-06-22 07:30:54,082 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:54,082 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 07:30:54,395 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 07:30:54,395 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:30:54,395 DEBUG --- stdout --- 2024-06-22 07:30:54,395 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 07:30:54,395 DEBUG --- stderr --- 2024-06-22 07:30:54,395 DEBUG 2024-06-22 07:30:54,396 INFO 2024-06-22 07:30:54,396 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- head -n 25 /results/orders/login-tree-250/stdout.txt | grep "started..." 2024-06-22 07:30:54,396 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 07:30:55,131 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-22 07:31:00,882 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-22 07:31:00,883 INFO [loop_until]: OK (rc = 0) 2024-06-22 07:31:00,883 DEBUG --- stdout --- 2024-06-22 07:31:00,883 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 07:31:00,883 DEBUG --- stderr --- 2024-06-22 07:31:00,883 DEBUG ________________________________________________________________________________ [2024-06-22 07:31:00] login-tree-250 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 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 08:33:10] login-tree-250 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-22 08:33:10,797 INFO 2024-06-22 08:33:10,798 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 08:33:10,798 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:11,136 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:11,136 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:11,136 DEBUG --- stdout --- 2024-06-22 08:33:11,136 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:11,136 DEBUG --- stderr --- 2024-06-22 08:33:11,136 DEBUG 2024-06-22 08:33:11,137 INFO 2024-06-22 08:33:11,137 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 08:33:11,137 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 08:33:11,934 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:11,934 DEBUG --- stdout --- 2024-06-22 08:33:11,934 DEBUG 2024-06-22 08:33:11,935 DEBUG --- stderr --- 2024-06-22 08:33:11,935 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-22 08:33:11] login-tree-250 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-22 08:33:11 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/order.json 2024-06-22 08:33:11,936 INFO 2024-06-22 08:33:11,936 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 08:33:11,936 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:12,240 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:12,241 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:12,241 DEBUG --- stdout --- 2024-06-22 08:33:12,241 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:12,241 DEBUG --- stderr --- 2024-06-22 08:33:12,241 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 08:33:12,241 INFO 2024-06-22 08:33:12,241 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 08:33:12,241 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:12,539 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:12,540 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:12,540 DEBUG --- stdout --- 2024-06-22 08:33:12,540 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:12,540 DEBUG --- stderr --- 2024-06-22 08:33:12,540 DEBUG 2024-06-22 08:33:12,540 INFO 2024-06-22 08:33:12,541 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-250/rc.txt" ]; then echo "/results/orders/login-tree-250/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-250/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/rc.txt file found" 2024-06-22 08:33:12,541 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 08:33:13,361 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 08:33:13,361 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:13,361 DEBUG --- stdout --- 2024-06-22 08:33:13,361 DEBUG /results/orders/login-tree-250/rc.txt file found 2024-06-22 08:33:13,361 DEBUG --- stderr --- 2024-06-22 08:33:13,361 DEBUG 2024-06-22 08:33:13,361 INFO 2024-06-22 08:33:13,362 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 08:33:13,362 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:13,683 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:13,683 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:13,683 DEBUG --- stdout --- 2024-06-22 08:33:13,683 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:13,683 DEBUG --- stderr --- 2024-06-22 08:33:13,683 DEBUG 2024-06-22 08:33:13,684 INFO 2024-06-22 08:33:13,684 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-250/rc.txt 2024-06-22 08:33:14,539 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 08:33:14,540 DEBUG --- stdout --- 2024-06-22 08:33:14,540 DEBUG 4 /results/orders/login-tree-250/rc.txt 2024-06-22 08:33:14,540 DEBUG --- stderr --- 2024-06-22 08:33:14,540 DEBUG 2024-06-22 08:33:14,540 INFO - Download file (size 4k) 2024-06-22 08:33:14,540 INFO 2024-06-22 08:33:14,540 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 08:33:14,540 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:14,863 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:14,864 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:14,864 DEBUG --- stdout --- 2024-06-22 08:33:14,864 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:14,864 DEBUG --- stderr --- 2024-06-22 08:33:14,864 DEBUG source : /results/orders/login-tree-250/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt 2024-06-22 08:33:14,864 INFO 2024-06-22 08:33:14,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 08:33:14,864 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:15,178 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:15,178 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:15,178 DEBUG --- stdout --- 2024-06-22 08:33:15,178 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:15,178 DEBUG --- stderr --- 2024-06-22 08:33:15,179 DEBUG 2024-06-22 08:33:15,179 INFO 2024-06-22 08:33:15,179 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-250/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt 2024-06-22 08:33:15,179 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 08:33:15,918 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:15,918 DEBUG --- stdout --- 2024-06-22 08:33:15,919 DEBUG tar: Removing leading `/' from member names 2024-06-22 08:33:15,919 DEBUG --- stderr --- 2024-06-22 08:33:15,919 DEBUG 2024-06-22 08:33:15,919 INFO 2024-06-22 08:33:15,919 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 08:33:15,919 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:16,224 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:16,225 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:16,225 DEBUG --- stdout --- 2024-06-22 08:33:16,225 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:16,225 DEBUG --- stderr --- 2024-06-22 08:33:16,225 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 08:33:16,225 INFO 2024-06-22 08:33:16,225 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 08:33:16,225 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:16,524 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:16,524 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:16,524 DEBUG --- stdout --- 2024-06-22 08:33:16,524 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:16,524 DEBUG --- stderr --- 2024-06-22 08:33:16,524 DEBUG 2024-06-22 08:33:16,525 INFO 2024-06-22 08:33:16,525 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-250/stderr.txt" ]; then echo "/results/orders/login-tree-250/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-250/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/stderr.txt file found" 2024-06-22 08:33:16,525 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 08:33:17,332 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 08:33:17,333 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:17,333 DEBUG --- stdout --- 2024-06-22 08:33:17,333 DEBUG /results/orders/login-tree-250/stderr.txt file found 2024-06-22 08:33:17,333 DEBUG --- stderr --- 2024-06-22 08:33:17,333 DEBUG 2024-06-22 08:33:17,333 INFO 2024-06-22 08:33:17,333 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 08:33:17,333 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:17,650 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:17,650 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:17,652 DEBUG --- stdout --- 2024-06-22 08:33:17,652 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:17,652 DEBUG --- stderr --- 2024-06-22 08:33:17,652 DEBUG 2024-06-22 08:33:17,652 INFO 2024-06-22 08:33:17,653 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-250/stderr.txt 2024-06-22 08:33:18,467 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 08:33:18,467 DEBUG --- stdout --- 2024-06-22 08:33:18,467 DEBUG 0 /results/orders/login-tree-250/stderr.txt 2024-06-22 08:33:18,467 DEBUG --- stderr --- 2024-06-22 08:33:18,467 DEBUG 2024-06-22 08:33:18,467 INFO - Download file (size 0k) 2024-06-22 08:33:18,468 INFO 2024-06-22 08:33:18,468 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 08:33:18,468 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:18,782 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:18,782 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:18,782 DEBUG --- stdout --- 2024-06-22 08:33:18,782 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:18,782 DEBUG --- stderr --- 2024-06-22 08:33:18,782 DEBUG source : /results/orders/login-tree-250/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt 2024-06-22 08:33:18,783 INFO 2024-06-22 08:33:18,783 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 08:33:18,783 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:19,091 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:19,091 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:19,091 DEBUG --- stdout --- 2024-06-22 08:33:19,091 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:19,091 DEBUG --- stderr --- 2024-06-22 08:33:19,091 DEBUG 2024-06-22 08:33:19,092 INFO 2024-06-22 08:33:19,092 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-250/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt 2024-06-22 08:33:19,092 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 08:33:19,815 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:19,816 DEBUG --- stdout --- 2024-06-22 08:33:19,816 DEBUG tar: Removing leading `/' from member names 2024-06-22 08:33:19,816 DEBUG --- stderr --- 2024-06-22 08:33:19,816 DEBUG 2024-06-22 08:33:19,816 INFO 2024-06-22 08:33:19,816 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 08:33:19,817 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:20,119 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:20,119 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:20,119 DEBUG --- stdout --- 2024-06-22 08:33:20,119 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:20,120 DEBUG --- stderr --- 2024-06-22 08:33:20,120 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 08:33:20,120 INFO 2024-06-22 08:33:20,120 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 08:33:20,120 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:20,429 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:20,429 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:20,429 DEBUG --- stdout --- 2024-06-22 08:33:20,429 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:20,429 DEBUG --- stderr --- 2024-06-22 08:33:20,429 DEBUG 2024-06-22 08:33:20,430 INFO 2024-06-22 08:33:20,430 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-250/stdout.txt" ]; then echo "/results/orders/login-tree-250/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-250/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/stdout.txt file found" 2024-06-22 08:33:20,430 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 08:33:21,233 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 08:33:21,234 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:21,234 DEBUG --- stdout --- 2024-06-22 08:33:21,234 DEBUG /results/orders/login-tree-250/stdout.txt file found 2024-06-22 08:33:21,234 DEBUG --- stderr --- 2024-06-22 08:33:21,234 DEBUG 2024-06-22 08:33:21,234 INFO 2024-06-22 08:33:21,234 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 08:33:21,234 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:21,536 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:21,536 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:21,536 DEBUG --- stdout --- 2024-06-22 08:33:21,537 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:21,537 DEBUG --- stderr --- 2024-06-22 08:33:21,537 DEBUG 2024-06-22 08:33:21,537 INFO 2024-06-22 08:33:21,537 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-250/stdout.txt 2024-06-22 08:33:22,345 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 08:33:22,345 DEBUG --- stdout --- 2024-06-22 08:33:22,345 DEBUG 1420 /results/orders/login-tree-250/stdout.txt 2024-06-22 08:33:22,345 DEBUG --- stderr --- 2024-06-22 08:33:22,345 DEBUG 2024-06-22 08:33:22,346 INFO - Download file (size 1420k) 2024-06-22 08:33:22,346 INFO 2024-06-22 08:33:22,346 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 08:33:22,346 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:22,661 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:22,661 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:22,662 DEBUG --- stdout --- 2024-06-22 08:33:22,662 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:22,662 DEBUG --- stderr --- 2024-06-22 08:33:22,662 DEBUG source : /results/orders/login-tree-250/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stdout.txt 2024-06-22 08:33:22,663 INFO 2024-06-22 08:33:22,663 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 08:33:22,663 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:22,969 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:22,969 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:22,969 DEBUG --- stdout --- 2024-06-22 08:33:22,969 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:22,969 DEBUG --- stderr --- 2024-06-22 08:33:22,969 DEBUG 2024-06-22 08:33:22,970 INFO 2024-06-22 08:33:22,970 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-250/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stdout.txt 2024-06-22 08:33:22,970 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 08:33:24,154 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:24,154 DEBUG --- stdout --- 2024-06-22 08:33:24,154 DEBUG tar: Removing leading `/' from member names 2024-06-22 08:33:24,154 DEBUG --- stderr --- 2024-06-22 08:33:24,154 DEBUG ________________________________________________________________________________ [2024-06-22 08:33:24] login-tree-250 show_cmd : Display order command ________________________________________________________________________________ 2024-06-22 08:33:24,155 INFO 2024-06-22 08:33:24,155 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 08:33:24,155 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:24,466 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:24,466 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:24,466 DEBUG --- stdout --- 2024-06-22 08:33:24,466 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:24,467 DEBUG --- stderr --- 2024-06-22 08:33:24,467 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 08:33:24,467 INFO 2024-06-22 08:33:24,467 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 08:33:24,467 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:24,783 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:24,783 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:24,783 DEBUG --- stdout --- 2024-06-22 08:33:24,783 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:24,783 DEBUG --- stderr --- 2024-06-22 08:33:24,783 DEBUG 2024-06-22 08:33:24,784 INFO 2024-06-22 08:33:24,784 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-250/cmd.txt" ]; then echo "/results/orders/login-tree-250/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-250/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/cmd.txt file found" 2024-06-22 08:33:24,784 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 08:33:25,594 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 08:33:25,595 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:25,595 DEBUG --- stdout --- 2024-06-22 08:33:25,595 DEBUG /results/orders/login-tree-250/cmd.txt file found 2024-06-22 08:33:25,595 DEBUG --- stderr --- 2024-06-22 08:33:25,595 DEBUG 2024-06-22 08:33:25,595 INFO 2024-06-22 08:33:25,595 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 08:33:25,595 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:25,923 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:25,923 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:25,923 DEBUG --- stdout --- 2024-06-22 08:33:25,923 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:25,923 DEBUG --- stderr --- 2024-06-22 08:33:25,923 DEBUG 2024-06-22 08:33:25,923 INFO 2024-06-22 08:33:25,923 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-250/cmd.txt 2024-06-22 08:33:26,741 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 08:33:26,742 DEBUG --- stdout --- 2024-06-22 08:33:26,742 DEBUG 4 /results/orders/login-tree-250/cmd.txt 2024-06-22 08:33:26,742 DEBUG --- stderr --- 2024-06-22 08:33:26,742 DEBUG 2024-06-22 08:33:26,742 INFO - Download file (size 4k) 2024-06-22 08:33:26,742 INFO 2024-06-22 08:33:26,742 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 08:33:26,742 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:27,063 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:27,063 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:27,063 DEBUG --- stdout --- 2024-06-22 08:33:27,063 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:27,063 DEBUG --- stderr --- 2024-06-22 08:33:27,063 DEBUG source : /results/orders/login-tree-250/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt 2024-06-22 08:33:27,064 INFO 2024-06-22 08:33:27,064 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 08:33:27,064 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 08:33:27,378 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 08:33:27,378 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:27,378 DEBUG --- stdout --- 2024-06-22 08:33:27,378 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 08:33:27,378 DEBUG --- stderr --- 2024-06-22 08:33:27,378 DEBUG 2024-06-22 08:33:27,379 INFO 2024-06-22 08:33:27,379 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-250/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt 2024-06-22 08:33:27,379 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 08:33:28,112 INFO [loop_until]: OK (rc = 0) 2024-06-22 08:33:28,112 DEBUG --- stdout --- 2024-06-22 08:33:28,112 DEBUG tar: Removing leading `/' from member names 2024-06-22 08:33:28,112 DEBUG --- stderr --- 2024-06-22 08:33:28,112 DEBUG 2024-06-22 08:33:28,113 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt is small (1 lines), printing all lines: 2024-06-22 08:33:28,113 DEBUG ----- output ----- 2024-06-22 08:33:28,113 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.loginProtect@ema-perf-p1" --results-folder /results/gatling/login-tree-250 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-22 08:33:28,113 INFO ________________________________________________________________________________ [2024-06-22 08:33:28] login-tree-250 show_rc : Display rc order ________________________________________________________________________________ 2024-06-22 08:33:28,113 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt is small (1 lines), printing all lines: 2024-06-22 08:33:28,114 DEBUG ----- output ----- 2024-06-22 08:33:28,114 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-22 08:33:28,114 INFO ________________________________________________________________________________ [2024-06-22 08:33:28] login-tree-250 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-22 08:33:28,117 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stdout.txt is 19539 lines): 2024-06-22 08:33:28,117 DEBUG ----- output ----- 2024-06-22 08:33:28,117 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-22 08:33:28,117 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-22 08:33:28,117 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-22 08:33:28,117 INFO 2024-06-22 08:33:28,117 INFO ================================================================================ 2024-06-22 08:33:28,117 INFO 2024-06-22 08:31:03 5s elapsed 2024-06-22 08:33:28,117 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 08:33:28,117 INFO > Global (OK=407 KO=0 ) 2024-06-22 08:33:28,117 INFO > protectLoginInitiate (OK=103 KO=0 ) 2024-06-22 08:33:28,117 INFO > PingOneProtectInitializeCallback (OK=101 KO=0 ) 2024-06-22 08:33:28,117 INFO > restLoginUsernamePasswordCallback (OK=99 KO=0 ) 2024-06-22 08:33:28,117 INFO > PingOneProtectEvaluationCallback (OK=88 KO=0 ) 2024-06-22 08:33:28,117 INFO > restloginProgressiveProfileCallback (OK=16 KO=0 ) 2024-06-22 08:33:28,117 INFO 2024-06-22 08:33:28,117 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 08:33:28,117 INFO [- ] 0% 2024-06-22 08:33:28,118 INFO waiting: 901976 / active: 19 / done: 86 2024-06-22 08:33:28,118 INFO ================================================================================ 2024-06-22 08:33:28,118 INFO 2024-06-22 08:33:28,118 INFO 2024-06-22 08:33:28,118 INFO ================================================================================ 2024-06-22 08:33:28,118 INFO 2024-06-22 08:31:08 10s elapsed 2024-06-22 08:33:28,118 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 08:33:28,118 INFO > Global (OK=1708 KO=0 ) 2024-06-22 08:33:28,118 INFO > protectLoginInitiate (OK=414 KO=0 ) 2024-06-22 08:33:28,118 INFO > PingOneProtectInitializeCallback (OK=410 KO=0 ) 2024-06-22 08:33:28,118 INFO > restLoginUsernamePasswordCallback (OK=404 KO=0 ) 2024-06-22 08:33:28,118 INFO > PingOneProtectEvaluationCallback (OK=383 KO=0 ) 2024-06-22 08:33:28,118 INFO > restloginProgressiveProfileCallback (OK=97 KO=0 ) 2024-06-22 08:33:28,118 INFO 2024-06-22 08:33:28,118 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 08:33:28,118 INFO [- ] 0% 2024-06-22 08:33:28,118 INFO waiting: 901663 / active: 36 / done: 382 2024-06-22 08:33:28,118 INFO ================================================================================ 2024-06-22 08:33:28,118 INFO 2024-06-22 08:33:28,118 INFO 2024-06-22 08:33:28,118 INFO ================================================================================ 2024-06-22 08:33:28,118 INFO 2024-06-22 08:31:13 15s elapsed 2024-06-22 08:33:28,118 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 08:33:28,118 INFO > Global (OK=3891 KO=0 ) 2024-06-22 08:33:28,118 INFO > protectLoginInitiate (OK=933 KO=0 ) 2024-06-22 08:33:28,118 INFO > PingOneProtectInitializeCallback (OK=927 KO=0 ) 2024-06-22 08:33:28,118 INFO > restLoginUsernamePasswordCallback (OK=919 KO=0 ) 2024-06-22 08:33:28,119 INFO > PingOneProtectEvaluationCallback (OK=886 KO=0 ) 2024-06-22 08:33:28,119 INFO > restloginProgressiveProfileCallback (OK=226 KO=0 ) 2024-06-22 08:33:28,119 INFO 2024-06-22 08:33:28,119 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 08:33:28,119 INFO [- ] 0% 2024-06-22 08:33:28,119 INFO waiting: 901142 / active: 58 / done: 881 2024-06-22 08:33:28,119 INFO ================================================================================ 2024-06-22 08:33:28,119 INFO 2024-06-22 08:33:28,119 INFO 2024-06-22 08:33:28,119 INFO ================================================================================ 2024-06-22 08:33:28,119 INFO 2024-06-22 08:31:18 20s elapsed 2024-06-22 08:33:28,119 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 08:33:28,119 INFO > Global (OK=6964 KO=0 ) 2024-06-22 08:33:28,119 INFO > protectLoginInitiate (OK=1660 KO=0 ) 2024-06-22 08:33:28,119 INFO > PingOneProtectInitializeCallback (OK=1653 KO=0 ) 2024-06-22 08:33:28,119 INFO > restLoginUsernamePasswordCallback (OK=1643 KO=0 ) 2024-06-22 08:33:28,119 INFO > PingOneProtectEvaluationCallback (OK=1597 KO=0 ) 2024-06-22 08:33:28,119 INFO > restloginProgressiveProfileCallback (OK=411 KO=0 ) 2024-06-22 08:33:28,119 INFO 2024-06-22 08:33:28,119 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 08:33:28,119 INFO [- ] 0% 2024-06-22 08:33:28,119 INFO waiting: 900412 / active: 84 / done: 1585 2024-06-22 08:33:28,119 INFO ================================================================================ 2024-06-22 08:33:28,119 INFO 2024-06-22 08:33:28,119 INFO 2024-06-22 08:33:28,119 INFO ================================================================================ 2024-06-22 08:33:28,119 INFO 2024-06-22 08:31:23 25s elapsed 2024-06-22 08:33:28,119 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 08:33:28,119 INFO > Global (OK=10919 KO=0 ) 2024-06-22 08:33:28,119 INFO > protectLoginInitiate (OK=2596 KO=0 ) 2024-06-22 08:33:28,119 INFO > PingOneProtectInitializeCallback (OK=2585 KO=0 ) 2024-06-22 08:33:28,119 INFO > restLoginUsernamePasswordCallback (OK=2567 KO=0 ) 2024-06-22 08:33:28,119 INFO > PingOneProtectEvaluationCallback (OK=2504 KO=0 ) 2024-06-22 08:33:28,119 INFO > restloginProgressiveProfileCallback (OK=667 KO=0 ) 2024-06-22 08:33:28,119 INFO 2024-06-22 08:33:28,119 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 08:33:28,119 INFO [- ] 0% 2024-06-22 08:33:28,120 INFO waiting: 899474 / active: 119 / done: 2488 2024-06-22 08:33:28,120 INFO ================================================================================ 2024-06-22 08:33:28,120 INFO 2024-06-22 08:33:28,120 INFO 2024-06-22 08:33:28,120 INFO ================================================================================ 2024-06-22 08:33:28,120 INFO 2024-06-22 08:31:28 30s elapsed 2024-06-22 08:33:28,120 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 08:33:28,120 INFO > Global (OK=15733 KO=0 ) 2024-06-22 08:33:28,120 INFO > protectLoginInitiate (OK=3740 KO=0 ) 2024-06-22 08:33:28,120 INFO > PingOneProtectInitializeCallback (OK=3722 KO=0 ) 2024-06-22 08:33:28,120 INFO > restLoginUsernamePasswordCallback (OK=3704 KO=0 ) 2024-06-22 08:33:28,120 INFO > PingOneProtectEvaluationCallback (OK=3609 KO=0 ) 2024-06-22 08:33:28,120 INFO > restloginProgressiveProfileCallback (OK=958 KO=0 ) 2024-06-22 08:33:28,120 INFO 2024-06-22 08:33:28,120 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 08:33:28,120 INFO [- ] 0% 2024-06-22 08:33:28,120 INFO waiting: 898326 / active: 160 / done: 3595 2024-06-22 08:33:28,120 INFO ================================================================================ 2024-06-22 08:33:28,120 INFO 2024-06-22 08:33:28,120 INFO 2024-06-22 08:33:28,120 INFO [...] 2024-06-22 08:33:28,126 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 08:33:28,126 INFO > Global (OK=3842842 KO=29 ) 2024-06-22 08:33:28,126 INFO > protectLoginInitiate (OK=900866 KO=3 ) 2024-06-22 08:33:28,126 INFO > PingOneProtectInitializeCallback (OK=900848 KO=1 ) 2024-06-22 08:33:28,126 INFO > restLoginUsernamePasswordCallback (OK=900813 KO=2 ) 2024-06-22 08:33:28,126 INFO > PingOneProtectEvaluationCallback (OK=900699 KO=12 ) 2024-06-22 08:33:28,126 INFO > restloginProgressiveProfileCallback (OK=239616 KO=11 ) 2024-06-22 08:33:28,126 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 08:33:28,126 INFO > status.find.is(200), but actually found 502 14 (34.15%) 2024-06-22 08:33:28,126 INFO > jsonPath($.tokenId).find.exists, found nothing 8 (19.51%) 2024-06-22 08:33:28,126 INFO > status.find.is(200), but actually found 401 7 (17.07%) 2024-06-22 08:33:28,126 INFO > PingOneProtectInitializeCallback: Failed to build request: No 3 ( 7.32%) 2024-06-22 08:33:28,126 INFO attribute named 'callbacks' is defined 2024-06-22 08:33:28,126 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 3 ( 7.32%) 2024-06-22 08:33:28,126 INFO attribute named 'callbacks' is defined 2024-06-22 08:33:28,126 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 3 ( 7.32%) 2024-06-22 08:33:28,126 INFO attribute named 'callbacks' is defined 2024-06-22 08:33:28,126 INFO > restloginProgressiveProfileCallback: Failed to build request: 3 ( 7.32%) 2024-06-22 08:33:28,126 INFO No attribute named 'callbacks' is defined 2024-06-22 08:33:28,126 INFO 2024-06-22 08:33:28,126 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 08:33:28,126 INFO [#########################################################################-] 99% 2024-06-22 08:33:28,126 INFO waiting: 1189 / active: 215 / done: 900677 2024-06-22 08:33:28,126 INFO ================================================================================ 2024-06-22 08:33:28,126 INFO 2024-06-22 08:33:28,126 INFO 2024-06-22 08:33:28,127 INFO ================================================================================ 2024-06-22 08:33:28,127 INFO 2024-06-22 09:31:28 3630s elapsed 2024-06-22 08:33:28,127 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 08:33:28,127 INFO > Global (OK=3848037 KO=29 ) 2024-06-22 08:33:28,127 INFO > protectLoginInitiate (OK=902065 KO=3 ) 2024-06-22 08:33:28,127 INFO > PingOneProtectInitializeCallback (OK=902050 KO=1 ) 2024-06-22 08:33:28,127 INFO > restLoginUsernamePasswordCallback (OK=902034 KO=2 ) 2024-06-22 08:33:28,127 INFO > PingOneProtectEvaluationCallback (OK=901940 KO=12 ) 2024-06-22 08:33:28,127 INFO > restloginProgressiveProfileCallback (OK=239948 KO=11 ) 2024-06-22 08:33:28,127 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 08:33:28,127 INFO > status.find.is(200), but actually found 502 14 (34.15%) 2024-06-22 08:33:28,127 INFO > jsonPath($.tokenId).find.exists, found nothing 8 (19.51%) 2024-06-22 08:33:28,127 INFO > status.find.is(200), but actually found 401 7 (17.07%) 2024-06-22 08:33:28,127 INFO > PingOneProtectInitializeCallback: Failed to build request: No 3 ( 7.32%) 2024-06-22 08:33:28,127 INFO attribute named 'callbacks' is defined 2024-06-22 08:33:28,127 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 3 ( 7.32%) 2024-06-22 08:33:28,127 INFO attribute named 'callbacks' is defined 2024-06-22 08:33:28,127 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 3 ( 7.32%) 2024-06-22 08:33:28,127 INFO attribute named 'callbacks' is defined 2024-06-22 08:33:28,127 INFO > restloginProgressiveProfileCallback: Failed to build request: 3 ( 7.32%) 2024-06-22 08:33:28,127 INFO No attribute named 'callbacks' is defined 2024-06-22 08:33:28,127 INFO 2024-06-22 08:33:28,127 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 08:33:28,127 INFO [#########################################################################-] 99% 2024-06-22 08:33:28,127 INFO waiting: 0 / active: 147 / done: 901934 2024-06-22 08:33:28,127 INFO ================================================================================ 2024-06-22 08:33:28,127 INFO 2024-06-22 08:33:28,127 INFO 2024-06-22 08:33:28,127 INFO ================================================================================ 2024-06-22 08:33:28,127 INFO 2024-06-22 09:31:29 3630s elapsed 2024-06-22 08:33:28,127 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 08:33:28,127 INFO > Global (OK=3848301 KO=29 ) 2024-06-22 08:33:28,127 INFO > protectLoginInitiate (OK=902078 KO=3 ) 2024-06-22 08:33:28,127 INFO > PingOneProtectInitializeCallback (OK=902077 KO=1 ) 2024-06-22 08:33:28,127 INFO > restLoginUsernamePasswordCallback (OK=902076 KO=2 ) 2024-06-22 08:33:28,127 INFO > PingOneProtectEvaluationCallback (OK=902066 KO=12 ) 2024-06-22 08:33:28,127 INFO > restloginProgressiveProfileCallback (OK=240004 KO=11 ) 2024-06-22 08:33:28,127 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 08:33:28,127 INFO > status.find.is(200), but actually found 502 14 (34.15%) 2024-06-22 08:33:28,127 INFO > jsonPath($.tokenId).find.exists, found nothing 8 (19.51%) 2024-06-22 08:33:28,127 INFO > status.find.is(200), but actually found 401 7 (17.07%) 2024-06-22 08:33:28,128 INFO > PingOneProtectInitializeCallback: Failed to build request: No 3 ( 7.32%) 2024-06-22 08:33:28,128 INFO attribute named 'callbacks' is defined 2024-06-22 08:33:28,128 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 3 ( 7.32%) 2024-06-22 08:33:28,128 INFO attribute named 'callbacks' is defined 2024-06-22 08:33:28,128 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 3 ( 7.32%) 2024-06-22 08:33:28,128 INFO attribute named 'callbacks' is defined 2024-06-22 08:33:28,128 INFO > restloginProgressiveProfileCallback: Failed to build request: 3 ( 7.32%) 2024-06-22 08:33:28,128 INFO No attribute named 'callbacks' is defined 2024-06-22 08:33:28,128 INFO 2024-06-22 08:33:28,128 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 08:33:28,128 INFO [##########################################################################]100% 2024-06-22 08:33:28,128 INFO waiting: 0 / active: 0 / done: 902081 2024-06-22 08:33:28,128 INFO ================================================================================ 2024-06-22 08:33:28,128 INFO 2024-06-22 08:33:28,128 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-22 08:33:28,128 INFO 2024-06-22 08:33:28,128 INFO ---------------------- 2024-06-22 08:33:28,128 INFO [22/Jun/2024 09:31:29] Create tar file login-tree-250.tar.gz 2024-06-22 08:33:28,128 INFO ---------------------- 2024-06-22 08:33:28,128 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-22 08:33:28,128 INFO RESULT_FOLDER=login-tree-250 2024-06-22 08:33:28,128 INFO SIMULATION_PATH=login-tree-250/loadtest-20240622073057606 2024-06-22 08:33:28,128 INFO PWD=/results/gatling/login-tree-250/loadtest-20240622073057606 2024-06-22 08:33:28,128 INFO [cmd] tar -czvf /results/gatling//login-tree-250.tar.gz --exclude=simulation.log * 2024-06-22 08:33:28,128 INFO /results/gatling 2024-06-22 08:33:28,128 INFO 2024-06-22 08:33:28,128 INFO ---------------------- 2024-06-22 08:33:28,128 INFO [22/Jun/2024 09:31:29] Update /results/gatling//latest 2024-06-22 08:33:28,128 INFO ---------------------- 2024-06-22 08:33:28,128 INFO 2024-06-22 08:33:28,128 INFO ---------------------- 2024-06-22 08:33:28,129 INFO [22/Jun/2024 09:31:29] Update /results/gatling//latest.tar.gz 2024-06-22 08:33:28,129 INFO ---------------------- 2024-06-22 08:33:28,129 INFO ________________________________________________________________________________ [2024-06-22 08:33:28] login-tree-250 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-22 08:33:28,129 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt is small (0 lines), printing all lines: 2024-06-22 08:33:28,129 DEBUG ----- output ----- 2024-06-22 08:33:28,129 DEBUG 2024-06-22 08:33:28,129 INFO ________________________________________________________________________________ [2024-06-22 08:33:28] login-tree-250 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-06-22 08:33:28] login-tree-250 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-22 08:33:28] login-tree-250 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped