--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.login', 'testMode': 'open', 'throughput': 150, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-23 23:25:23] login-tree-150 pre : N/A ________________________________________________________________________________ 2024-06-23 23:25:23 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-23 23:25:23 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-150/stdout.txt Pod log : /results/orders/login-tree-150 2024-06-23 23:25:23,732 INFO 2024-06-23 23:25:23,732 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 23:25:23,732 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:24,041 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:24,041 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:24,041 DEBUG --- stdout --- 2024-06-23 23:25:24,041 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:24,041 DEBUG --- stderr --- 2024-06-23 23:25:24,041 DEBUG ________________________________________________________________________________ [2024-06-23 23:25:24] login-tree-150 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-23 23:25:24,042 INFO 2024-06-23 23:25:24,042 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 23:25:24,042 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:24,345 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:24,345 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:24,345 DEBUG --- stdout --- 2024-06-23 23:25:24,345 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:24,345 DEBUG --- stderr --- 2024-06-23 23:25:24,345 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.login@ema-perf-p1" --results-folder /results/gatling/login-tree-150 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-23 23:25:24] login-tree-150 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-23 23:25:24,346 INFO 2024-06-23 23:25:24,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-23 23:25:24,346 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:24,649 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:24,650 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:24,650 DEBUG --- stdout --- 2024-06-23 23:25:24,650 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:24,650 DEBUG --- stderr --- 2024-06-23 23:25:24,650 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.login@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 -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.login -DtestMode=open -Dthroughput=150 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-23 23:25:24] login-tree-150 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-23 23:25:24,652 INFO 2024-06-23 23:25:24,652 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-23 23:25:24,652 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 23:25:24,890 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 23:25:24,890 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:24,890 DEBUG --- stdout --- 2024-06-23 23:25:24,890 DEBUG WAITING 2024-06-23 23:25:24,890 DEBUG --- stderr --- 2024-06-23 23:25:24,890 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-23 23:25:24] login-tree-150 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-23 23:25:24,891 INFO 2024-06-23 23:25:24,891 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 23:25:24,891 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:25,197 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:25,197 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:25,197 DEBUG --- stdout --- 2024-06-23 23:25:25,197 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:25,197 DEBUG --- stderr --- 2024-06-23 23:25:25,197 DEBUG 2024-06-23 23:25:25,198 INFO 2024-06-23 23:25:25,198 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-23 23:25:25,198 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 23:25:26,624 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:26,624 DEBUG --- stdout --- 2024-06-23 23:25:26,624 DEBUG 2024-06-23 23:25:26,624 DEBUG --- stderr --- 2024-06-23 23:25:26,624 DEBUG Order has been successfully forwarded to overseer 2024-06-23 23:25:26 - INFO: Check if Gatling simulation started successfully 2024-06-23 23:25:26,625 INFO 2024-06-23 23:25:26,625 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 23:25:26,625 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:26,997 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:26,998 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:26,998 DEBUG --- stdout --- 2024-06-23 23:25:26,998 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:26,998 DEBUG --- stderr --- 2024-06-23 23:25:26,998 DEBUG 2024-06-23 23:25:26,998 INFO 2024-06-23 23:25:26,998 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- head -n 25 /results/orders/login-tree-150/stdout.txt | grep "started..." 2024-06-23 23:25:26,998 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 23:25:27,742 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-23 23:25:33,483 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-23 23:25:33,484 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:33,484 DEBUG --- stdout --- 2024-06-23 23:25:33,484 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.4 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 23:25:33,484 DEBUG --- stderr --- 2024-06-23 23:25:33,484 DEBUG ________________________________________________________________________________ [2024-06-23 23:25:33] 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 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-24 00:27:43] login-tree-150 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-24 00:27:43,795 INFO 2024-06-24 00:27:43,795 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-24 00:27:43,795 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:44,161 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:44,161 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:44,161 DEBUG --- stdout --- 2024-06-24 00:27:44,161 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:44,161 DEBUG --- stderr --- 2024-06-24 00:27:44,161 DEBUG 2024-06-24 00:27:44,162 INFO 2024-06-24 00:27:44,162 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-24 00:27:44,162 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 00:27:44,954 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:44,954 DEBUG --- stdout --- 2024-06-24 00:27:44,954 DEBUG 2024-06-24 00:27:44,954 DEBUG --- stderr --- 2024-06-24 00:27:44,954 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-24 00:27:44] login-tree-150 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-24 00:27:44 - 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-24 00:27:44,955 INFO 2024-06-24 00:27:44,955 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-24 00:27:44,955 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:45,259 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:45,260 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:45,260 DEBUG --- stdout --- 2024-06-24 00:27:45,260 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:45,260 DEBUG --- stderr --- 2024-06-24 00:27:45,260 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 00:27:45,260 INFO 2024-06-24 00:27:45,260 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-24 00:27:45,260 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:45,566 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:45,566 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:45,566 DEBUG --- stdout --- 2024-06-24 00:27:45,566 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:45,566 DEBUG --- stderr --- 2024-06-24 00:27:45,566 DEBUG 2024-06-24 00:27:45,567 INFO 2024-06-24 00:27:45,567 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-24 00:27:45,567 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 00:27:46,375 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 00:27:46,375 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:46,375 DEBUG --- stdout --- 2024-06-24 00:27:46,375 DEBUG /results/orders/login-tree-150/rc.txt file found 2024-06-24 00:27:46,376 DEBUG --- stderr --- 2024-06-24 00:27:46,376 DEBUG 2024-06-24 00:27:46,376 INFO 2024-06-24 00:27:46,376 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-24 00:27:46,376 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:46,698 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:46,698 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:46,698 DEBUG --- stdout --- 2024-06-24 00:27:46,698 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:46,698 DEBUG --- stderr --- 2024-06-24 00:27:46,698 DEBUG 2024-06-24 00:27:46,698 INFO 2024-06-24 00:27:46,699 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-24 00:27:47,517 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 00:27:47,517 DEBUG --- stdout --- 2024-06-24 00:27:47,518 DEBUG 4 /results/orders/login-tree-150/rc.txt 2024-06-24 00:27:47,518 DEBUG --- stderr --- 2024-06-24 00:27:47,518 DEBUG 2024-06-24 00:27:47,518 INFO - Download file (size 4k) 2024-06-24 00:27:47,518 INFO 2024-06-24 00:27:47,518 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-24 00:27:47,518 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:47,863 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:47,863 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:47,863 DEBUG --- stdout --- 2024-06-24 00:27:47,863 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:47,863 DEBUG --- stderr --- 2024-06-24 00:27:47,863 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-24 00:27:47,864 INFO 2024-06-24 00:27:47,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-24 00:27:47,864 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:48,172 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:48,172 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:48,172 DEBUG --- stdout --- 2024-06-24 00:27:48,172 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:48,172 DEBUG --- stderr --- 2024-06-24 00:27:48,172 DEBUG 2024-06-24 00:27:48,172 INFO 2024-06-24 00:27:48,172 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-24 00:27:48,173 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 00:27:48,938 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:48,938 DEBUG --- stdout --- 2024-06-24 00:27:48,938 DEBUG tar: Removing leading `/' from member names 2024-06-24 00:27:48,938 DEBUG --- stderr --- 2024-06-24 00:27:48,938 DEBUG 2024-06-24 00:27:48,938 INFO 2024-06-24 00:27:48,939 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-24 00:27:48,939 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:49,242 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:49,243 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:49,243 DEBUG --- stdout --- 2024-06-24 00:27:49,243 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:49,243 DEBUG --- stderr --- 2024-06-24 00:27:49,243 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 00:27:49,243 INFO 2024-06-24 00:27:49,243 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-24 00:27:49,243 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:49,564 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:49,564 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:49,564 DEBUG --- stdout --- 2024-06-24 00:27:49,565 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:49,565 DEBUG --- stderr --- 2024-06-24 00:27:49,565 DEBUG 2024-06-24 00:27:49,565 INFO 2024-06-24 00:27:49,565 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-24 00:27:49,565 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 00:27:50,393 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 00:27:50,393 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:50,393 DEBUG --- stdout --- 2024-06-24 00:27:50,394 DEBUG /results/orders/login-tree-150/stderr.txt file found 2024-06-24 00:27:50,394 DEBUG --- stderr --- 2024-06-24 00:27:50,394 DEBUG 2024-06-24 00:27:50,394 INFO 2024-06-24 00:27:50,394 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-24 00:27:50,394 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:50,785 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:50,785 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:50,785 DEBUG --- stdout --- 2024-06-24 00:27:50,785 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:50,785 DEBUG --- stderr --- 2024-06-24 00:27:50,785 DEBUG 2024-06-24 00:27:50,785 INFO 2024-06-24 00:27:50,786 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-24 00:27:51,619 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 00:27:51,619 DEBUG --- stdout --- 2024-06-24 00:27:51,619 DEBUG 0 /results/orders/login-tree-150/stderr.txt 2024-06-24 00:27:51,619 DEBUG --- stderr --- 2024-06-24 00:27:51,619 DEBUG 2024-06-24 00:27:51,619 INFO - Download file (size 0k) 2024-06-24 00:27:51,620 INFO 2024-06-24 00:27:51,620 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-24 00:27:51,620 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:51,945 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:51,946 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:51,946 DEBUG --- stdout --- 2024-06-24 00:27:51,946 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:51,946 DEBUG --- stderr --- 2024-06-24 00:27:51,946 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-24 00:27:51,946 INFO 2024-06-24 00:27:51,946 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-24 00:27:51,946 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:52,262 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:52,263 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:52,263 DEBUG --- stdout --- 2024-06-24 00:27:52,263 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:52,263 DEBUG --- stderr --- 2024-06-24 00:27:52,263 DEBUG 2024-06-24 00:27:52,263 INFO 2024-06-24 00:27:52,263 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-24 00:27:52,263 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 00:27:53,050 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:53,050 DEBUG --- stdout --- 2024-06-24 00:27:53,050 DEBUG tar: Removing leading `/' from member names 2024-06-24 00:27:53,050 DEBUG --- stderr --- 2024-06-24 00:27:53,050 DEBUG 2024-06-24 00:27:53,051 INFO 2024-06-24 00:27:53,051 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-24 00:27:53,051 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:53,357 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:53,358 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:53,358 DEBUG --- stdout --- 2024-06-24 00:27:53,358 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:53,358 DEBUG --- stderr --- 2024-06-24 00:27:53,358 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 00:27:53,358 INFO 2024-06-24 00:27:53,358 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-24 00:27:53,358 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:53,686 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:53,686 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:53,686 DEBUG --- stdout --- 2024-06-24 00:27:53,686 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:53,686 DEBUG --- stderr --- 2024-06-24 00:27:53,686 DEBUG 2024-06-24 00:27:53,687 INFO 2024-06-24 00:27:53,687 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-24 00:27:53,687 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 00:27:54,502 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 00:27:54,502 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:54,502 DEBUG --- stdout --- 2024-06-24 00:27:54,502 DEBUG /results/orders/login-tree-150/stdout.txt file found 2024-06-24 00:27:54,502 DEBUG --- stderr --- 2024-06-24 00:27:54,502 DEBUG 2024-06-24 00:27:54,503 INFO 2024-06-24 00:27:54,503 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-24 00:27:54,503 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:54,816 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:54,817 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:54,817 DEBUG --- stdout --- 2024-06-24 00:27:54,817 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:54,817 DEBUG --- stderr --- 2024-06-24 00:27:54,817 DEBUG 2024-06-24 00:27:54,817 INFO 2024-06-24 00:27:54,817 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-24 00:27:55,622 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 00:27:55,622 DEBUG --- stdout --- 2024-06-24 00:27:55,622 DEBUG 828 /results/orders/login-tree-150/stdout.txt 2024-06-24 00:27:55,623 DEBUG --- stderr --- 2024-06-24 00:27:55,623 DEBUG 2024-06-24 00:27:55,623 INFO - Download file (size 828k) 2024-06-24 00:27:55,623 INFO 2024-06-24 00:27:55,623 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-24 00:27:55,623 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:55,975 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:55,975 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:55,975 DEBUG --- stdout --- 2024-06-24 00:27:55,975 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:55,975 DEBUG --- stderr --- 2024-06-24 00:27:55,975 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-24 00:27:55,976 INFO 2024-06-24 00:27:55,976 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-24 00:27:55,976 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:56,348 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:56,348 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:56,348 DEBUG --- stdout --- 2024-06-24 00:27:56,348 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:56,348 DEBUG --- stderr --- 2024-06-24 00:27:56,348 DEBUG 2024-06-24 00:27:56,349 INFO 2024-06-24 00:27:56,349 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-24 00:27:56,349 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 00:27:57,443 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:57,444 DEBUG --- stdout --- 2024-06-24 00:27:57,444 DEBUG tar: Removing leading `/' from member names 2024-06-24 00:27:57,444 DEBUG --- stderr --- 2024-06-24 00:27:57,444 DEBUG ________________________________________________________________________________ [2024-06-24 00:27:57] login-tree-150 show_cmd : Display order command ________________________________________________________________________________ 2024-06-24 00:27:57,445 INFO 2024-06-24 00:27:57,445 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-24 00:27:57,445 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:57,784 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:57,785 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:57,785 DEBUG --- stdout --- 2024-06-24 00:27:57,785 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:57,785 DEBUG --- stderr --- 2024-06-24 00:27:57,785 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 00:27:57,785 INFO 2024-06-24 00:27:57,785 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-24 00:27:57,785 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:58,104 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:58,105 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:58,105 DEBUG --- stdout --- 2024-06-24 00:27:58,105 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:58,105 DEBUG --- stderr --- 2024-06-24 00:27:58,105 DEBUG 2024-06-24 00:27:58,106 INFO 2024-06-24 00:27:58,106 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-24 00:27:58,106 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 00:27:58,927 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 00:27:58,927 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:58,927 DEBUG --- stdout --- 2024-06-24 00:27:58,927 DEBUG /results/orders/login-tree-150/cmd.txt file found 2024-06-24 00:27:58,927 DEBUG --- stderr --- 2024-06-24 00:27:58,927 DEBUG 2024-06-24 00:27:58,928 INFO 2024-06-24 00:27:58,928 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-24 00:27:58,928 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:27:59,230 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:27:59,230 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:27:59,232 DEBUG --- stdout --- 2024-06-24 00:27:59,232 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:27:59,232 DEBUG --- stderr --- 2024-06-24 00:27:59,232 DEBUG 2024-06-24 00:27:59,232 INFO 2024-06-24 00:27:59,233 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-24 00:28:00,050 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 00:28:00,050 DEBUG --- stdout --- 2024-06-24 00:28:00,050 DEBUG 4 /results/orders/login-tree-150/cmd.txt 2024-06-24 00:28:00,050 DEBUG --- stderr --- 2024-06-24 00:28:00,050 DEBUG 2024-06-24 00:28:00,050 INFO - Download file (size 4k) 2024-06-24 00:28:00,051 INFO 2024-06-24 00:28:00,051 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-24 00:28:00,051 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:28:00,377 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:28:00,377 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:28:00,377 DEBUG --- stdout --- 2024-06-24 00:28:00,377 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:28:00,377 DEBUG --- stderr --- 2024-06-24 00:28:00,377 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-24 00:28:00,378 INFO 2024-06-24 00:28:00,378 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-24 00:28:00,378 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 00:28:00,697 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 00:28:00,698 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:28:00,698 DEBUG --- stdout --- 2024-06-24 00:28:00,698 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 00:28:00,698 DEBUG --- stderr --- 2024-06-24 00:28:00,698 DEBUG 2024-06-24 00:28:00,698 INFO 2024-06-24 00:28:00,698 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-24 00:28:00,698 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 00:28:01,430 INFO [loop_until]: OK (rc = 0) 2024-06-24 00:28:01,431 DEBUG --- stdout --- 2024-06-24 00:28:01,431 DEBUG tar: Removing leading `/' from member names 2024-06-24 00:28:01,431 DEBUG --- stderr --- 2024-06-24 00:28:01,431 DEBUG 2024-06-24 00:28:01,431 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-24 00:28:01,431 DEBUG ----- output ----- 2024-06-24 00:28:01,431 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.login@ema-perf-p1" --results-folder /results/gatling/login-tree-150 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-24 00:28:01,431 INFO ________________________________________________________________________________ [2024-06-24 00:28:01] login-tree-150 show_rc : Display rc order ________________________________________________________________________________ 2024-06-24 00:28:01,431 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-24 00:28:01,431 DEBUG ----- output ----- 2024-06-24 00:28:01,431 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-24 00:28:01,431 INFO ________________________________________________________________________________ [2024-06-24 00:28:01] login-tree-150 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-24 00:28:01,433 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 12390 lines): 2024-06-24 00:28:01,434 DEBUG ----- output ----- 2024-06-24 00:28:01,434 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-24 00:28:01,434 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-24 00:28:01,434 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-24 00:28:01,434 INFO 2024-06-24 00:28:01,434 INFO ================================================================================ 2024-06-24 00:28:01,434 INFO 2024-06-24 00:25:35 5s elapsed 2024-06-24 00:28:01,434 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,434 INFO > Global (OK=123 KO=0 ) 2024-06-24 00:28:01,434 INFO > restLoginInitiate (OK=62 KO=0 ) 2024-06-24 00:28:01,434 INFO > restLoginUsernamePasswordCallback (OK=59 KO=0 ) 2024-06-24 00:28:01,434 INFO > restloginProgressiveProfileCallback (OK=2 KO=0 ) 2024-06-24 00:28:01,434 INFO 2024-06-24 00:28:01,434 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,434 INFO [- ] 0% 2024-06-24 00:28:01,434 INFO waiting: 541924 / active: 4 / done: 59 2024-06-24 00:28:01,434 INFO ================================================================================ 2024-06-24 00:28:01,434 INFO 2024-06-24 00:28:01,434 INFO 2024-06-24 00:28:01,434 INFO ================================================================================ 2024-06-24 00:28:01,434 INFO 2024-06-24 00:25:40 10s elapsed 2024-06-24 00:28:01,434 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,434 INFO > Global (OK=509 KO=0 ) 2024-06-24 00:28:01,434 INFO > restLoginInitiate (OK=249 KO=0 ) 2024-06-24 00:28:01,434 INFO > restLoginUsernamePasswordCallback (OK=244 KO=0 ) 2024-06-24 00:28:01,434 INFO > restloginProgressiveProfileCallback (OK=16 KO=0 ) 2024-06-24 00:28:01,434 INFO 2024-06-24 00:28:01,434 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,434 INFO [- ] 0% 2024-06-24 00:28:01,434 INFO waiting: 541736 / active: 7 / done: 244 2024-06-24 00:28:01,434 INFO ================================================================================ 2024-06-24 00:28:01,434 INFO 2024-06-24 00:28:01,434 INFO 2024-06-24 00:28:01,435 INFO ================================================================================ 2024-06-24 00:28:01,435 INFO 2024-06-24 00:25:45 15s elapsed 2024-06-24 00:28:01,435 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,435 INFO > Global (OK=1141 KO=0 ) 2024-06-24 00:28:01,435 INFO > restLoginInitiate (OK=560 KO=0 ) 2024-06-24 00:28:01,435 INFO > restLoginUsernamePasswordCallback (OK=552 KO=0 ) 2024-06-24 00:28:01,435 INFO > restloginProgressiveProfileCallback (OK=29 KO=0 ) 2024-06-24 00:28:01,435 INFO 2024-06-24 00:28:01,435 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,435 INFO [- ] 0% 2024-06-24 00:28:01,435 INFO waiting: 541423 / active: 13 / done: 551 2024-06-24 00:28:01,435 INFO ================================================================================ 2024-06-24 00:28:01,435 INFO 2024-06-24 00:28:01,435 INFO 2024-06-24 00:28:01,435 INFO ================================================================================ 2024-06-24 00:28:01,435 INFO 2024-06-24 00:25:50 20s elapsed 2024-06-24 00:28:01,435 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,435 INFO > Global (OK=2035 KO=0 ) 2024-06-24 00:28:01,435 INFO > restLoginInitiate (OK=997 KO=0 ) 2024-06-24 00:28:01,435 INFO > restLoginUsernamePasswordCallback (OK=986 KO=0 ) 2024-06-24 00:28:01,435 INFO > restloginProgressiveProfileCallback (OK=52 KO=0 ) 2024-06-24 00:28:01,435 INFO 2024-06-24 00:28:01,435 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,435 INFO [- ] 0% 2024-06-24 00:28:01,435 INFO waiting: 540985 / active: 17 / done: 985 2024-06-24 00:28:01,435 INFO ================================================================================ 2024-06-24 00:28:01,435 INFO 2024-06-24 00:28:01,435 INFO 2024-06-24 00:28:01,435 INFO ================================================================================ 2024-06-24 00:28:01,435 INFO 2024-06-24 00:25:55 25s elapsed 2024-06-24 00:28:01,435 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,435 INFO > Global (OK=3179 KO=0 ) 2024-06-24 00:28:01,435 INFO > restLoginInitiate (OK=1558 KO=0 ) 2024-06-24 00:28:01,435 INFO > restLoginUsernamePasswordCallback (OK=1544 KO=0 ) 2024-06-24 00:28:01,435 INFO > restloginProgressiveProfileCallback (OK=77 KO=0 ) 2024-06-24 00:28:01,435 INFO 2024-06-24 00:28:01,435 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,435 INFO [- ] 0% 2024-06-24 00:28:01,435 INFO waiting: 540423 / active: 22 / done: 1542 2024-06-24 00:28:01,435 INFO ================================================================================ 2024-06-24 00:28:01,436 INFO 2024-06-24 00:28:01,436 INFO 2024-06-24 00:28:01,436 INFO ================================================================================ 2024-06-24 00:28:01,436 INFO 2024-06-24 00:26:00 30s elapsed 2024-06-24 00:28:01,436 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,436 INFO > Global (OK=4600 KO=0 ) 2024-06-24 00:28:01,436 INFO > restLoginInitiate (OK=2245 KO=0 ) 2024-06-24 00:28:01,436 INFO > restLoginUsernamePasswordCallback (OK=2229 KO=0 ) 2024-06-24 00:28:01,436 INFO > restloginProgressiveProfileCallback (OK=126 KO=0 ) 2024-06-24 00:28:01,436 INFO 2024-06-24 00:28:01,436 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,436 INFO [- ] 0% 2024-06-24 00:28:01,436 INFO waiting: 539733 / active: 27 / done: 2227 2024-06-24 00:28:01,436 INFO ================================================================================ 2024-06-24 00:28:01,436 INFO 2024-06-24 00:28:01,436 INFO 2024-06-24 00:28:01,436 INFO ================================================================================ 2024-06-24 00:28:01,436 INFO 2024-06-24 00:26:05 35s elapsed 2024-06-24 00:28:01,436 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,436 INFO > Global (OK=6174 KO=0 ) 2024-06-24 00:28:01,436 INFO > restLoginInitiate (OK=3009 KO=0 ) 2024-06-24 00:28:01,436 INFO > restLoginUsernamePasswordCallback (OK=2995 KO=0 ) 2024-06-24 00:28:01,436 INFO > restloginProgressiveProfileCallback (OK=170 KO=0 ) 2024-06-24 00:28:01,436 INFO 2024-06-24 00:28:01,436 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,436 INFO [- ] 0% 2024-06-24 00:28:01,436 INFO waiting: 538976 / active: 17 / done: 2994 2024-06-24 00:28:01,436 INFO ================================================================================ 2024-06-24 00:28:01,436 INFO [...] 2024-06-24 00:28:01,439 INFO 2024-06-24 00:28:01,439 INFO 2024-06-24 00:28:01,439 INFO ================================================================================ 2024-06-24 00:28:01,439 INFO 2024-06-24 01:25:45 3615s elapsed 2024-06-24 00:28:01,439 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,440 INFO > Global (OK=1140869 KO=17 ) 2024-06-24 00:28:01,440 INFO > restLoginInitiate (OK=539703 KO=7 ) 2024-06-24 00:28:01,440 INFO > restLoginUsernamePasswordCallback (OK=539674 KO=10 ) 2024-06-24 00:28:01,440 INFO > restloginProgressiveProfileCallback (OK=61492 KO=0 ) 2024-06-24 00:28:01,440 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 00:28:01,440 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-24 00:28:01,440 INFO 2024-06-24 00:28:01,440 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,440 INFO [#########################################################################-] 99% 2024-06-24 00:28:01,440 INFO waiting: 2267 / active: 33 / done: 539687 2024-06-24 00:28:01,440 INFO ================================================================================ 2024-06-24 00:28:01,440 INFO 2024-06-24 00:28:01,440 INFO 2024-06-24 00:28:01,440 INFO ================================================================================ 2024-06-24 00:28:01,440 INFO 2024-06-24 01:25:50 3620s elapsed 2024-06-24 00:28:01,440 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,440 INFO > Global (OK=1142403 KO=17 ) 2024-06-24 00:28:01,440 INFO > restLoginInitiate (OK=540418 KO=7 ) 2024-06-24 00:28:01,440 INFO > restLoginUsernamePasswordCallback (OK=540392 KO=10 ) 2024-06-24 00:28:01,440 INFO > restloginProgressiveProfileCallback (OK=61593 KO=0 ) 2024-06-24 00:28:01,440 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 00:28:01,440 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-24 00:28:01,440 INFO 2024-06-24 00:28:01,440 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,440 INFO [#########################################################################-] 99% 2024-06-24 00:28:01,440 INFO waiting: 1548 / active: 34 / done: 540405 2024-06-24 00:28:01,440 INFO ================================================================================ 2024-06-24 00:28:01,440 INFO 2024-06-24 00:28:01,440 INFO 2024-06-24 00:28:01,440 INFO ================================================================================ 2024-06-24 00:28:01,440 INFO 2024-06-24 01:25:55 3625s elapsed 2024-06-24 00:28:01,440 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,440 INFO > Global (OK=1144121 KO=17 ) 2024-06-24 00:28:01,440 INFO > restLoginInitiate (OK=541206 KO=7 ) 2024-06-24 00:28:01,440 INFO > restLoginUsernamePasswordCallback (OK=541180 KO=10 ) 2024-06-24 00:28:01,440 INFO > restloginProgressiveProfileCallback (OK=61735 KO=0 ) 2024-06-24 00:28:01,440 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 00:28:01,440 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-24 00:28:01,440 INFO 2024-06-24 00:28:01,441 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,441 INFO [#########################################################################-] 99% 2024-06-24 00:28:01,441 INFO waiting: 764 / active: 28 / done: 541195 2024-06-24 00:28:01,441 INFO ================================================================================ 2024-06-24 00:28:01,441 INFO 2024-06-24 00:28:01,441 INFO 2024-06-24 00:28:01,441 INFO ================================================================================ 2024-06-24 00:28:01,441 INFO 2024-06-24 01:26:00 3630s elapsed 2024-06-24 00:28:01,441 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,441 INFO > Global (OK=1145816 KO=17 ) 2024-06-24 00:28:01,441 INFO > restLoginInitiate (OK=541974 KO=7 ) 2024-06-24 00:28:01,441 INFO > restLoginUsernamePasswordCallback (OK=541944 KO=10 ) 2024-06-24 00:28:01,441 INFO > restloginProgressiveProfileCallback (OK=61898 KO=0 ) 2024-06-24 00:28:01,441 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 00:28:01,441 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-24 00:28:01,441 INFO 2024-06-24 00:28:01,441 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,441 INFO [#########################################################################-] 99% 2024-06-24 00:28:01,441 INFO waiting: 0 / active: 32 / done: 541955 2024-06-24 00:28:01,441 INFO ================================================================================ 2024-06-24 00:28:01,441 INFO 2024-06-24 00:28:01,441 INFO 2024-06-24 00:28:01,441 INFO ================================================================================ 2024-06-24 00:28:01,441 INFO 2024-06-24 01:26:01 3630s elapsed 2024-06-24 00:28:01,441 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 00:28:01,441 INFO > Global (OK=1145857 KO=17 ) 2024-06-24 00:28:01,441 INFO > restLoginInitiate (OK=541980 KO=7 ) 2024-06-24 00:28:01,441 INFO > restLoginUsernamePasswordCallback (OK=541970 KO=10 ) 2024-06-24 00:28:01,441 INFO > restloginProgressiveProfileCallback (OK=61907 KO=0 ) 2024-06-24 00:28:01,441 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 00:28:01,441 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-24 00:28:01,441 INFO 2024-06-24 00:28:01,441 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 00:28:01,441 INFO [##########################################################################]100% 2024-06-24 00:28:01,441 INFO waiting: 0 / active: 0 / done: 541987 2024-06-24 00:28:01,441 INFO ================================================================================ 2024-06-24 00:28:01,441 INFO 2024-06-24 00:28:01,441 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-24 00:28:01,441 INFO 2024-06-24 00:28:01,442 INFO ---------------------- 2024-06-24 00:28:01,442 INFO [24/Jun/2024 01:26:01] Create tar file login-tree-150.tar.gz 2024-06-24 00:28:01,442 INFO ---------------------- 2024-06-24 00:28:01,442 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-24 00:28:01,442 INFO RESULT_FOLDER=login-tree-150 2024-06-24 00:28:01,442 INFO SIMULATION_PATH=login-tree-150/loadtest-20240623232530176 2024-06-24 00:28:01,442 INFO PWD=/results/gatling/login-tree-150/loadtest-20240623232530176 2024-06-24 00:28:01,442 INFO [cmd] tar -czvf /results/gatling//login-tree-150.tar.gz --exclude=simulation.log * 2024-06-24 00:28:01,442 INFO /results/gatling 2024-06-24 00:28:01,442 INFO 2024-06-24 00:28:01,442 INFO ---------------------- 2024-06-24 00:28:01,442 INFO [24/Jun/2024 01:26:01] Update /results/gatling//latest 2024-06-24 00:28:01,442 INFO ---------------------- 2024-06-24 00:28:01,442 INFO 2024-06-24 00:28:01,442 INFO ---------------------- 2024-06-24 00:28:01,442 INFO [24/Jun/2024 01:26:01] Update /results/gatling//latest.tar.gz 2024-06-24 00:28:01,442 INFO ---------------------- 2024-06-24 00:28:01,442 INFO ________________________________________________________________________________ [2024-06-24 00:28:01] login-tree-150 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-24 00:28:01,442 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-24 00:28:01,442 DEBUG ----- output ----- 2024-06-24 00:28:01,442 DEBUG 2024-06-24 00:28:01,442 INFO ________________________________________________________________________________ [2024-06-24 00:28:01] 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-24 00:28:01] login-tree-150 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-24 00:28:01] login-tree-150 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped