--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-22 20:16:45] login-tree-150 pre : N/A ________________________________________________________________________________ 2024-06-22 20:16:45 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-22 20:16:45 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-150/stdout.txt Pod log : /results/orders/login-tree-150 2024-06-22 20:16:45,086 INFO 2024-06-22 20:16:45,087 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 20:16:45,087 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:45,406 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:45,406 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:45,406 DEBUG --- stdout --- 2024-06-22 20:16:45,406 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:45,406 DEBUG --- stderr --- 2024-06-22 20:16:45,406 DEBUG ________________________________________________________________________________ [2024-06-22 20:16:45] login-tree-150 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-22 20:16:45,407 INFO 2024-06-22 20:16:45,407 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 20:16:45,407 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:45,736 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:45,736 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:45,736 DEBUG --- stdout --- 2024-06-22 20:16:45,736 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:45,736 DEBUG --- stderr --- 2024-06-22 20:16:45,736 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-22 20:16:45] login-tree-150 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-22 20:16:45,737 INFO 2024-06-22 20:16:45,737 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 20:16:45,737 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:46,059 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:46,059 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:46,059 DEBUG --- stdout --- 2024-06-22 20:16:46,059 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:46,059 DEBUG --- stderr --- 2024-06-22 20:16:46,059 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-22 20:16:46] login-tree-150 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-22 20:16:46,062 INFO 2024-06-22 20:16:46,062 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-22 20:16:46,063 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 20:16:46,317 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 20:16:46,318 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:46,318 DEBUG --- stdout --- 2024-06-22 20:16:46,318 DEBUG WAITING 2024-06-22 20:16:46,318 DEBUG --- stderr --- 2024-06-22 20:16:46,318 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-06-22 20:16:46] login-tree-150 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-22 20:16:46,319 INFO 2024-06-22 20:16:46,319 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 20:16:46,319 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:46,635 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:46,636 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:46,636 DEBUG --- stdout --- 2024-06-22 20:16:46,636 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:46,636 DEBUG --- stderr --- 2024-06-22 20:16:46,636 DEBUG 2024-06-22 20:16:46,636 INFO 2024-06-22 20:16:46,636 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-22 20:16:46,636 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 20:16:48,068 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:48,068 DEBUG --- stdout --- 2024-06-22 20:16:48,068 DEBUG 2024-06-22 20:16:48,068 DEBUG --- stderr --- 2024-06-22 20:16:48,068 DEBUG Order has been successfully forwarded to overseer 2024-06-22 20:16:48 - INFO: Check if Gatling simulation started successfully 2024-06-22 20:16:48,068 INFO 2024-06-22 20:16:48,068 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 20:16:48,068 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:48,403 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:48,403 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:48,403 DEBUG --- stdout --- 2024-06-22 20:16:48,403 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:48,403 DEBUG --- stderr --- 2024-06-22 20:16:48,403 DEBUG 2024-06-22 20:16:48,404 INFO 2024-06-22 20:16:48,404 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- head -n 25 /results/orders/login-tree-150/stdout.txt | grep "started..." 2024-06-22 20:16:48,404 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 20:16:49,143 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-22 20:16:54,888 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-22 20:16:54,889 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:54,889 DEBUG --- stdout --- 2024-06-22 20:16:54,889 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 20:16:54,889 DEBUG --- stderr --- 2024-06-22 20:16:54,889 DEBUG ________________________________________________________________________________ [2024-06-22 20:16:54] login-tree-150 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 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 05s, 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 08s, 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 21:19:04] login-tree-150 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-22 21:19:04,878 INFO 2024-06-22 21:19:04,878 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 21:19:04,879 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:05,199 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:05,199 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:05,199 DEBUG --- stdout --- 2024-06-22 21:19:05,199 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:05,199 DEBUG --- stderr --- 2024-06-22 21:19:05,199 DEBUG 2024-06-22 21:19:05,199 INFO 2024-06-22 21:19:05,199 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 21:19:05,200 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 21:19:05,991 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:05,992 DEBUG --- stdout --- 2024-06-22 21:19:05,993 DEBUG 2024-06-22 21:19:05,993 DEBUG --- stderr --- 2024-06-22 21:19:05,993 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-22 21:19:05] login-tree-150 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-22 21:19:05 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/order.json 2024-06-22 21:19:05,994 INFO 2024-06-22 21:19:05,994 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 21:19:05,994 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:06,299 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:06,300 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:06,300 DEBUG --- stdout --- 2024-06-22 21:19:06,300 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:06,300 DEBUG --- stderr --- 2024-06-22 21:19:06,300 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 21:19:06,300 INFO 2024-06-22 21:19:06,300 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 21:19:06,300 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:06,607 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:06,608 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:06,608 DEBUG --- stdout --- 2024-06-22 21:19:06,608 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:06,608 DEBUG --- stderr --- 2024-06-22 21:19:06,608 DEBUG 2024-06-22 21:19:06,608 INFO 2024-06-22 21:19:06,609 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/rc.txt" ]; then echo "/results/orders/login-tree-150/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-150/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/rc.txt file found" 2024-06-22 21:19:06,609 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 21:19:07,588 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 21:19:07,588 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:07,588 DEBUG --- stdout --- 2024-06-22 21:19:07,589 DEBUG /results/orders/login-tree-150/rc.txt file found 2024-06-22 21:19:07,589 DEBUG --- stderr --- 2024-06-22 21:19:07,589 DEBUG 2024-06-22 21:19:07,589 INFO 2024-06-22 21:19:07,589 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 21:19:07,589 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:07,907 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:07,907 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:07,907 DEBUG --- stdout --- 2024-06-22 21:19:07,907 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:07,907 DEBUG --- stderr --- 2024-06-22 21:19:07,907 DEBUG 2024-06-22 21:19:07,907 INFO 2024-06-22 21:19:07,908 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-150/rc.txt 2024-06-22 21:19:08,713 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 21:19:08,713 DEBUG --- stdout --- 2024-06-22 21:19:08,713 DEBUG 4 /results/orders/login-tree-150/rc.txt 2024-06-22 21:19:08,713 DEBUG --- stderr --- 2024-06-22 21:19:08,713 DEBUG 2024-06-22 21:19:08,713 INFO - Download file (size 4k) 2024-06-22 21:19:08,714 INFO 2024-06-22 21:19:08,714 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 21:19:08,714 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:09,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:09,030 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:09,030 DEBUG --- stdout --- 2024-06-22 21:19:09,030 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:09,030 DEBUG --- stderr --- 2024-06-22 21:19:09,030 DEBUG source : /results/orders/login-tree-150/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/rc.txt 2024-06-22 21:19:09,030 INFO 2024-06-22 21:19:09,030 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 21:19:09,030 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:09,351 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:09,351 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:09,351 DEBUG --- stdout --- 2024-06-22 21:19:09,351 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:09,352 DEBUG --- stderr --- 2024-06-22 21:19:09,352 DEBUG 2024-06-22 21:19:09,352 INFO 2024-06-22 21:19:09,352 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-150/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/rc.txt 2024-06-22 21:19:09,352 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 21:19:10,096 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:10,096 DEBUG --- stdout --- 2024-06-22 21:19:10,097 DEBUG tar: Removing leading `/' from member names 2024-06-22 21:19:10,097 DEBUG --- stderr --- 2024-06-22 21:19:10,097 DEBUG 2024-06-22 21:19:10,097 INFO 2024-06-22 21:19:10,097 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 21:19:10,097 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:10,405 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:10,405 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:10,405 DEBUG --- stdout --- 2024-06-22 21:19:10,405 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:10,405 DEBUG --- stderr --- 2024-06-22 21:19:10,405 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 21:19:10,405 INFO 2024-06-22 21:19:10,406 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 21:19:10,406 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:10,714 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:10,714 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:10,714 DEBUG --- stdout --- 2024-06-22 21:19:10,714 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:10,714 DEBUG --- stderr --- 2024-06-22 21:19:10,714 DEBUG 2024-06-22 21:19:10,715 INFO 2024-06-22 21:19:10,715 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/stderr.txt" ]; then echo "/results/orders/login-tree-150/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-150/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/stderr.txt file found" 2024-06-22 21:19:10,715 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 21:19:11,541 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 21:19:11,541 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:11,543 DEBUG --- stdout --- 2024-06-22 21:19:11,543 DEBUG /results/orders/login-tree-150/stderr.txt file found 2024-06-22 21:19:11,543 DEBUG --- stderr --- 2024-06-22 21:19:11,543 DEBUG 2024-06-22 21:19:11,544 INFO 2024-06-22 21:19:11,544 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 21:19:11,544 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:11,861 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:11,862 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:11,862 DEBUG --- stdout --- 2024-06-22 21:19:11,862 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:11,862 DEBUG --- stderr --- 2024-06-22 21:19:11,862 DEBUG 2024-06-22 21:19:11,862 INFO 2024-06-22 21:19:11,862 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-150/stderr.txt 2024-06-22 21:19:12,662 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 21:19:12,663 DEBUG --- stdout --- 2024-06-22 21:19:12,663 DEBUG 0 /results/orders/login-tree-150/stderr.txt 2024-06-22 21:19:12,663 DEBUG --- stderr --- 2024-06-22 21:19:12,663 DEBUG 2024-06-22 21:19:12,663 INFO - Download file (size 0k) 2024-06-22 21:19:12,663 INFO 2024-06-22 21:19:12,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 21:19:12,663 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:12,966 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:12,966 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:12,966 DEBUG --- stdout --- 2024-06-22 21:19:12,966 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:12,967 DEBUG --- stderr --- 2024-06-22 21:19:12,967 DEBUG source : /results/orders/login-tree-150/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/stderr.txt 2024-06-22 21:19:12,967 INFO 2024-06-22 21:19:12,967 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 21:19:12,967 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:13,273 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:13,273 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:13,273 DEBUG --- stdout --- 2024-06-22 21:19:13,273 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:13,273 DEBUG --- stderr --- 2024-06-22 21:19:13,273 DEBUG 2024-06-22 21:19:13,274 INFO 2024-06-22 21:19:13,274 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-150/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/stderr.txt 2024-06-22 21:19:13,274 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 21:19:14,057 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:14,057 DEBUG --- stdout --- 2024-06-22 21:19:14,057 DEBUG tar: Removing leading `/' from member names 2024-06-22 21:19:14,058 DEBUG --- stderr --- 2024-06-22 21:19:14,058 DEBUG 2024-06-22 21:19:14,058 INFO 2024-06-22 21:19:14,058 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 21:19:14,058 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:14,363 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:14,364 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:14,364 DEBUG --- stdout --- 2024-06-22 21:19:14,364 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:14,364 DEBUG --- stderr --- 2024-06-22 21:19:14,364 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 21:19:14,364 INFO 2024-06-22 21:19:14,364 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 21:19:14,364 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:14,675 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:14,676 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:14,676 DEBUG --- stdout --- 2024-06-22 21:19:14,676 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:14,676 DEBUG --- stderr --- 2024-06-22 21:19:14,676 DEBUG 2024-06-22 21:19:14,677 INFO 2024-06-22 21:19:14,677 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/stdout.txt" ]; then echo "/results/orders/login-tree-150/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-150/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/stdout.txt file found" 2024-06-22 21:19:14,677 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 21:19:15,481 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 21:19:15,481 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:15,481 DEBUG --- stdout --- 2024-06-22 21:19:15,481 DEBUG /results/orders/login-tree-150/stdout.txt file found 2024-06-22 21:19:15,481 DEBUG --- stderr --- 2024-06-22 21:19:15,481 DEBUG 2024-06-22 21:19:15,482 INFO 2024-06-22 21:19:15,482 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 21:19:15,482 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:15,784 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:15,785 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:15,785 DEBUG --- stdout --- 2024-06-22 21:19:15,785 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:15,785 DEBUG --- stderr --- 2024-06-22 21:19:15,785 DEBUG 2024-06-22 21:19:15,785 INFO 2024-06-22 21:19:15,785 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-150/stdout.txt 2024-06-22 21:19:16,607 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 21:19:16,607 DEBUG --- stdout --- 2024-06-22 21:19:16,609 DEBUG 792 /results/orders/login-tree-150/stdout.txt 2024-06-22 21:19:16,609 DEBUG --- stderr --- 2024-06-22 21:19:16,609 DEBUG 2024-06-22 21:19:16,609 INFO - Download file (size 792k) 2024-06-22 21:19:16,610 INFO 2024-06-22 21:19:16,610 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 21:19:16,610 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:16,931 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:16,931 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:16,931 DEBUG --- stdout --- 2024-06-22 21:19:16,931 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:16,931 DEBUG --- stderr --- 2024-06-22 21:19:16,931 DEBUG source : /results/orders/login-tree-150/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/stdout.txt 2024-06-22 21:19:16,932 INFO 2024-06-22 21:19:16,932 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 21:19:16,932 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:17,237 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:17,237 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:17,238 DEBUG --- stdout --- 2024-06-22 21:19:17,238 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:17,238 DEBUG --- stderr --- 2024-06-22 21:19:17,238 DEBUG 2024-06-22 21:19:17,239 INFO 2024-06-22 21:19:17,239 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-150/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/stdout.txt 2024-06-22 21:19:17,239 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 21:19:18,331 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:18,331 DEBUG --- stdout --- 2024-06-22 21:19:18,331 DEBUG tar: Removing leading `/' from member names 2024-06-22 21:19:18,331 DEBUG --- stderr --- 2024-06-22 21:19:18,331 DEBUG ________________________________________________________________________________ [2024-06-22 21:19:18] login-tree-150 show_cmd : Display order command ________________________________________________________________________________ 2024-06-22 21:19:18,332 INFO 2024-06-22 21:19:18,332 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 21:19:18,332 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:18,630 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:18,630 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:18,630 DEBUG --- stdout --- 2024-06-22 21:19:18,631 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:18,631 DEBUG --- stderr --- 2024-06-22 21:19:18,631 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 21:19:18,631 INFO 2024-06-22 21:19:18,631 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 21:19:18,631 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:18,952 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:18,953 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:18,953 DEBUG --- stdout --- 2024-06-22 21:19:18,953 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:18,953 DEBUG --- stderr --- 2024-06-22 21:19:18,953 DEBUG 2024-06-22 21:19:18,953 INFO 2024-06-22 21:19:18,953 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/cmd.txt" ]; then echo "/results/orders/login-tree-150/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-150/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/cmd.txt file found" 2024-06-22 21:19:18,953 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 21:19:19,767 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 21:19:19,767 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:19,767 DEBUG --- stdout --- 2024-06-22 21:19:19,767 DEBUG /results/orders/login-tree-150/cmd.txt file found 2024-06-22 21:19:19,767 DEBUG --- stderr --- 2024-06-22 21:19:19,767 DEBUG 2024-06-22 21:19:19,768 INFO 2024-06-22 21:19:19,768 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 21:19:19,768 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:20,095 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:20,095 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:20,095 DEBUG --- stdout --- 2024-06-22 21:19:20,095 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:20,095 DEBUG --- stderr --- 2024-06-22 21:19:20,095 DEBUG 2024-06-22 21:19:20,095 INFO 2024-06-22 21:19:20,096 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-150/cmd.txt 2024-06-22 21:19:20,904 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 21:19:20,904 DEBUG --- stdout --- 2024-06-22 21:19:20,904 DEBUG 4 /results/orders/login-tree-150/cmd.txt 2024-06-22 21:19:20,905 DEBUG --- stderr --- 2024-06-22 21:19:20,905 DEBUG 2024-06-22 21:19:20,905 INFO - Download file (size 4k) 2024-06-22 21:19:20,905 INFO 2024-06-22 21:19:20,905 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 21:19:20,905 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:21,213 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:21,213 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:21,213 DEBUG --- stdout --- 2024-06-22 21:19:21,213 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:21,213 DEBUG --- stderr --- 2024-06-22 21:19:21,213 DEBUG source : /results/orders/login-tree-150/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/cmd.txt 2024-06-22 21:19:21,213 INFO 2024-06-22 21:19:21,214 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 21:19:21,214 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:21,517 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:21,517 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:21,517 DEBUG --- stdout --- 2024-06-22 21:19:21,517 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:21,517 DEBUG --- stderr --- 2024-06-22 21:19:21,517 DEBUG 2024-06-22 21:19:21,517 INFO 2024-06-22 21:19:21,517 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-150/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/cmd.txt 2024-06-22 21:19:21,517 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 21:19:22,250 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:22,250 DEBUG --- stdout --- 2024-06-22 21:19:22,250 DEBUG tar: Removing leading `/' from member names 2024-06-22 21:19:22,250 DEBUG --- stderr --- 2024-06-22 21:19:22,250 DEBUG 2024-06-22 21:19:22,251 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/cmd.txt is small (1 lines), printing all lines: 2024-06-22 21:19:22,251 DEBUG ----- output ----- 2024-06-22 21:19:22,251 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-22 21:19:22,251 INFO ________________________________________________________________________________ [2024-06-22 21:19:22] login-tree-150 show_rc : Display rc order ________________________________________________________________________________ 2024-06-22 21:19:22,251 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/rc.txt is small (1 lines), printing all lines: 2024-06-22 21:19:22,251 DEBUG ----- output ----- 2024-06-22 21:19:22,252 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-22 21:19:22,252 INFO ________________________________________________________________________________ [2024-06-22 21:19:22] login-tree-150 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-22 21:19:22,254 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 12169 lines): 2024-06-22 21:19:22,254 DEBUG ----- output ----- 2024-06-22 21:19:22,254 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-22 21:19:22,254 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-22 21:19:22,254 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-22 21:19:22,254 INFO 2024-06-22 21:19:22,254 INFO ================================================================================ 2024-06-22 21:19:22,254 INFO 2024-06-22 21:16:57 5s elapsed 2024-06-22 21:19:22,254 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,254 INFO > Global (OK=123 KO=0 ) 2024-06-22 21:19:22,254 INFO > restLoginInitiate (OK=62 KO=0 ) 2024-06-22 21:19:22,254 INFO > restLoginUsernamePasswordCallback (OK=59 KO=0 ) 2024-06-22 21:19:22,254 INFO > restloginProgressiveProfileCallback (OK=2 KO=0 ) 2024-06-22 21:19:22,254 INFO 2024-06-22 21:19:22,254 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,254 INFO [- ] 0% 2024-06-22 21:19:22,254 INFO waiting: 542924 / active: 4 / done: 59 2024-06-22 21:19:22,254 INFO ================================================================================ 2024-06-22 21:19:22,254 INFO 2024-06-22 21:19:22,254 INFO 2024-06-22 21:19:22,254 INFO ================================================================================ 2024-06-22 21:19:22,254 INFO 2024-06-22 21:17:02 10s elapsed 2024-06-22 21:19:22,254 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,254 INFO > Global (OK=499 KO=0 ) 2024-06-22 21:19:22,254 INFO > restLoginInitiate (OK=248 KO=0 ) 2024-06-22 21:19:22,254 INFO > restLoginUsernamePasswordCallback (OK=243 KO=0 ) 2024-06-22 21:19:22,254 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-22 21:19:22,254 INFO 2024-06-22 21:19:22,254 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,254 INFO [- ] 0% 2024-06-22 21:19:22,255 INFO waiting: 542736 / active: 8 / done: 243 2024-06-22 21:19:22,255 INFO ================================================================================ 2024-06-22 21:19:22,255 INFO 2024-06-22 21:19:22,255 INFO 2024-06-22 21:19:22,255 INFO ================================================================================ 2024-06-22 21:19:22,255 INFO 2024-06-22 21:17:07 15s elapsed 2024-06-22 21:19:22,255 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,255 INFO > Global (OK=1139 KO=0 ) 2024-06-22 21:19:22,255 INFO > restLoginInitiate (OK=559 KO=0 ) 2024-06-22 21:19:22,255 INFO > restLoginUsernamePasswordCallback (OK=551 KO=0 ) 2024-06-22 21:19:22,255 INFO > restloginProgressiveProfileCallback (OK=29 KO=0 ) 2024-06-22 21:19:22,255 INFO 2024-06-22 21:19:22,255 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,255 INFO [- ] 0% 2024-06-22 21:19:22,255 INFO waiting: 542424 / active: 12 / done: 551 2024-06-22 21:19:22,255 INFO ================================================================================ 2024-06-22 21:19:22,255 INFO 2024-06-22 21:19:22,255 INFO 2024-06-22 21:19:22,255 INFO ================================================================================ 2024-06-22 21:19:22,255 INFO 2024-06-22 21:17:12 20s elapsed 2024-06-22 21:19:22,255 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,255 INFO > Global (OK=2033 KO=0 ) 2024-06-22 21:19:22,255 INFO > restLoginInitiate (OK=996 KO=0 ) 2024-06-22 21:19:22,255 INFO > restLoginUsernamePasswordCallback (OK=985 KO=0 ) 2024-06-22 21:19:22,255 INFO > restloginProgressiveProfileCallback (OK=52 KO=0 ) 2024-06-22 21:19:22,255 INFO 2024-06-22 21:19:22,255 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,255 INFO [- ] 0% 2024-06-22 21:19:22,255 INFO waiting: 541985 / active: 18 / done: 984 2024-06-22 21:19:22,255 INFO ================================================================================ 2024-06-22 21:19:22,255 INFO 2024-06-22 21:19:22,255 INFO 2024-06-22 21:19:22,255 INFO ================================================================================ 2024-06-22 21:19:22,255 INFO 2024-06-22 21:17:17 25s elapsed 2024-06-22 21:19:22,255 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,255 INFO > Global (OK=3175 KO=0 ) 2024-06-22 21:19:22,255 INFO > restLoginInitiate (OK=1558 KO=0 ) 2024-06-22 21:19:22,255 INFO > restLoginUsernamePasswordCallback (OK=1543 KO=0 ) 2024-06-22 21:19:22,255 INFO > restloginProgressiveProfileCallback (OK=74 KO=0 ) 2024-06-22 21:19:22,255 INFO 2024-06-22 21:19:22,255 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,256 INFO [- ] 0% 2024-06-22 21:19:22,256 INFO waiting: 541423 / active: 21 / done: 1543 2024-06-22 21:19:22,256 INFO ================================================================================ 2024-06-22 21:19:22,256 INFO 2024-06-22 21:19:22,256 INFO 2024-06-22 21:19:22,256 INFO ================================================================================ 2024-06-22 21:19:22,256 INFO 2024-06-22 21:17:22 30s elapsed 2024-06-22 21:19:22,256 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,256 INFO > Global (OK=4574 KO=0 ) 2024-06-22 21:19:22,256 INFO > restLoginInitiate (OK=2245 KO=0 ) 2024-06-22 21:19:22,256 INFO > restLoginUsernamePasswordCallback (OK=2228 KO=0 ) 2024-06-22 21:19:22,256 INFO > restloginProgressiveProfileCallback (OK=101 KO=0 ) 2024-06-22 21:19:22,256 INFO 2024-06-22 21:19:22,256 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,256 INFO [- ] 0% 2024-06-22 21:19:22,256 INFO waiting: 540736 / active: 24 / done: 2227 2024-06-22 21:19:22,256 INFO ================================================================================ 2024-06-22 21:19:22,256 INFO 2024-06-22 21:19:22,256 INFO 2024-06-22 21:19:22,256 INFO ================================================================================ 2024-06-22 21:19:22,256 INFO 2024-06-22 21:17:27 35s elapsed 2024-06-22 21:19:22,256 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,256 INFO > Global (OK=6074 KO=0 ) 2024-06-22 21:19:22,256 INFO > restLoginInitiate (OK=2973 KO=0 ) 2024-06-22 21:19:22,256 INFO > restLoginUsernamePasswordCallback (OK=2957 KO=0 ) 2024-06-22 21:19:22,256 INFO > restloginProgressiveProfileCallback (OK=144 KO=0 ) 2024-06-22 21:19:22,256 INFO 2024-06-22 21:19:22,256 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,256 INFO [- ] 0% 2024-06-22 21:19:22,256 INFO waiting: 540005 / active: 27 / done: 2955 2024-06-22 21:19:22,256 INFO ================================================================================ 2024-06-22 21:19:22,256 INFO [...] 2024-06-22 21:19:22,259 INFO 2024-06-22 21:19:22,260 INFO 2024-06-22 21:19:22,260 INFO ================================================================================ 2024-06-22 21:19:22,260 INFO 2024-06-22 22:17:07 3615s elapsed 2024-06-22 21:19:22,260 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,260 INFO > Global (OK=1142824 KO=17 ) 2024-06-22 21:19:22,260 INFO > restLoginInitiate (OK=540724 KO=10 ) 2024-06-22 21:19:22,260 INFO > restLoginUsernamePasswordCallback (OK=540693 KO=7 ) 2024-06-22 21:19:22,260 INFO > restloginProgressiveProfileCallback (OK=61407 KO=0 ) 2024-06-22 21:19:22,260 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 21:19:22,260 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-22 21:19:22,260 INFO 2024-06-22 21:19:22,260 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,260 INFO [#########################################################################-] 99% 2024-06-22 21:19:22,260 INFO waiting: 2247 / active: 31 / done: 540709 2024-06-22 21:19:22,260 INFO ================================================================================ 2024-06-22 21:19:22,260 INFO 2024-06-22 21:19:22,260 INFO 2024-06-22 21:19:22,260 INFO ================================================================================ 2024-06-22 21:19:22,260 INFO 2024-06-22 22:17:12 3620s elapsed 2024-06-22 21:19:22,260 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,260 INFO > Global (OK=1144449 KO=17 ) 2024-06-22 21:19:22,260 INFO > restLoginInitiate (OK=541471 KO=10 ) 2024-06-22 21:19:22,260 INFO > restLoginUsernamePasswordCallback (OK=541442 KO=7 ) 2024-06-22 21:19:22,260 INFO > restloginProgressiveProfileCallback (OK=61536 KO=0 ) 2024-06-22 21:19:22,260 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 21:19:22,260 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-22 21:19:22,260 INFO 2024-06-22 21:19:22,260 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,260 INFO [#########################################################################-] 99% 2024-06-22 21:19:22,260 INFO waiting: 1496 / active: 40 / done: 541451 2024-06-22 21:19:22,260 INFO ================================================================================ 2024-06-22 21:19:22,260 INFO 2024-06-22 21:19:22,260 INFO 2024-06-22 21:19:22,260 INFO ================================================================================ 2024-06-22 21:19:22,260 INFO 2024-06-22 22:17:17 3625s elapsed 2024-06-22 21:19:22,260 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,260 INFO > Global (OK=1146066 KO=17 ) 2024-06-22 21:19:22,260 INFO > restLoginInitiate (OK=542218 KO=10 ) 2024-06-22 21:19:22,260 INFO > restLoginUsernamePasswordCallback (OK=542195 KO=7 ) 2024-06-22 21:19:22,260 INFO > restloginProgressiveProfileCallback (OK=61653 KO=0 ) 2024-06-22 21:19:22,261 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 21:19:22,261 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-22 21:19:22,261 INFO 2024-06-22 21:19:22,261 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,261 INFO [#########################################################################-] 99% 2024-06-22 21:19:22,261 INFO waiting: 755 / active: 21 / done: 542211 2024-06-22 21:19:22,261 INFO ================================================================================ 2024-06-22 21:19:22,261 INFO 2024-06-22 21:19:22,261 INFO 2024-06-22 21:19:22,261 INFO ================================================================================ 2024-06-22 21:19:22,261 INFO 2024-06-22 22:17:22 3630s elapsed 2024-06-22 21:19:22,261 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,261 INFO > Global (OK=1147720 KO=17 ) 2024-06-22 21:19:22,261 INFO > restLoginInitiate (OK=542977 KO=10 ) 2024-06-22 21:19:22,261 INFO > restLoginUsernamePasswordCallback (OK=542952 KO=7 ) 2024-06-22 21:19:22,261 INFO > restloginProgressiveProfileCallback (OK=61791 KO=0 ) 2024-06-22 21:19:22,261 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 21:19:22,261 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-22 21:19:22,261 INFO 2024-06-22 21:19:22,261 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,261 INFO [#########################################################################-] 99% 2024-06-22 21:19:22,261 INFO waiting: 0 / active: 22 / done: 542965 2024-06-22 21:19:22,261 INFO ================================================================================ 2024-06-22 21:19:22,261 INFO 2024-06-22 21:19:22,261 INFO 2024-06-22 21:19:22,261 INFO ================================================================================ 2024-06-22 21:19:22,261 INFO 2024-06-22 22:17:22 3630s elapsed 2024-06-22 21:19:22,261 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 21:19:22,261 INFO > Global (OK=1147743 KO=17 ) 2024-06-22 21:19:22,261 INFO > restLoginInitiate (OK=542977 KO=10 ) 2024-06-22 21:19:22,261 INFO > restLoginUsernamePasswordCallback (OK=542970 KO=7 ) 2024-06-22 21:19:22,261 INFO > restloginProgressiveProfileCallback (OK=61796 KO=0 ) 2024-06-22 21:19:22,261 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 21:19:22,261 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-22 21:19:22,261 INFO 2024-06-22 21:19:22,261 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 21:19:22,261 INFO [##########################################################################]100% 2024-06-22 21:19:22,261 INFO waiting: 0 / active: 0 / done: 542987 2024-06-22 21:19:22,261 INFO ================================================================================ 2024-06-22 21:19:22,262 INFO 2024-06-22 21:19:22,262 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-22 21:19:22,262 INFO 2024-06-22 21:19:22,262 INFO ---------------------- 2024-06-22 21:19:22,262 INFO [22/Jun/2024 22:17:22] Create tar file login-tree-150.tar.gz 2024-06-22 21:19:22,262 INFO ---------------------- 2024-06-22 21:19:22,262 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-22 21:19:22,262 INFO RESULT_FOLDER=login-tree-150 2024-06-22 21:19:22,262 INFO SIMULATION_PATH=login-tree-150/loadtest-20240622201651645 2024-06-22 21:19:22,262 INFO PWD=/results/gatling/login-tree-150/loadtest-20240622201651645 2024-06-22 21:19:22,262 INFO [cmd] tar -czvf /results/gatling//login-tree-150.tar.gz --exclude=simulation.log * 2024-06-22 21:19:22,262 INFO /results/gatling 2024-06-22 21:19:22,262 INFO 2024-06-22 21:19:22,262 INFO ---------------------- 2024-06-22 21:19:22,262 INFO [22/Jun/2024 22:17:22] Update /results/gatling//latest 2024-06-22 21:19:22,262 INFO ---------------------- 2024-06-22 21:19:22,262 INFO 2024-06-22 21:19:22,262 INFO ---------------------- 2024-06-22 21:19:22,262 INFO [22/Jun/2024 22:17:22] Update /results/gatling//latest.tar.gz 2024-06-22 21:19:22,262 INFO ---------------------- 2024-06-22 21:19:22,262 INFO ________________________________________________________________________________ [2024-06-22 21:19:22] login-tree-150 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-22 21:19:22,263 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-150/stderr.txt is small (0 lines), printing all lines: 2024-06-22 21:19:22,263 DEBUG ----- output ----- 2024-06-22 21:19:22,263 DEBUG 2024-06-22 21:19:22,263 INFO ________________________________________________________________________________ [2024-06-22 21:19:22] login-tree-150 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-06-22 21:19:22] login-tree-150 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-22 21:19:22] login-tree-150 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped