--Task-- name: login-tree-300 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-275'] preceding_task: login-tree-275 options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 300, '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 00:27:16] login-tree-300 pre : N/A ________________________________________________________________________________ 2024-06-23 00:27:16 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-23 00:27:16 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-300/stdout.txt Pod log : /results/orders/login-tree-300 2024-06-23 00:27:16,966 INFO 2024-06-23 00:27:16,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-23 00:27:16,967 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:17,287 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:17,288 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:17,288 DEBUG --- stdout --- 2024-06-23 00:27:17,288 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:17,288 DEBUG --- stderr --- 2024-06-23 00:27:17,288 DEBUG ________________________________________________________________________________ [2024-06-23 00:27:17] login-tree-300 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-23 00:27:17,289 INFO 2024-06-23 00:27:17,289 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 00:27:17,289 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:17,608 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:17,608 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:17,608 DEBUG --- stdout --- 2024-06-23 00:27:17,608 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:17,608 DEBUG --- stderr --- 2024-06-23 00:27:17,608 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-300 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-23 00:27:17] login-tree-300 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-23 00:27:17,609 INFO 2024-06-23 00:27:17,609 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 00:27:17,609 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:17,930 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:17,931 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:17,931 DEBUG --- stdout --- 2024-06-23 00:27:17,931 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:17,931 DEBUG --- stderr --- 2024-06-23 00:27:17,931 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/order.json { "name": "login-tree-300", "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-300 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-300", "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=300 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-23 00:27:17] login-tree-300 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 00:27:17,933 INFO 2024-06-23 00:27:17,933 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-23 00:27:17,933 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 00:27:18,184 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 00:27:18,184 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:18,184 DEBUG --- stdout --- 2024-06-23 00:27:18,184 DEBUG WAITING 2024-06-23 00:27:18,184 DEBUG --- stderr --- 2024-06-23 00:27:18,184 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 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-06-23 00:27:18] login-tree-300 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-23 00:27:18,185 INFO 2024-06-23 00:27:18,185 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 00:27:18,185 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:18,503 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:18,504 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:18,504 DEBUG --- stdout --- 2024-06-23 00:27:18,504 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:18,504 DEBUG --- stderr --- 2024-06-23 00:27:18,504 DEBUG 2024-06-23 00:27:18,504 INFO 2024-06-23 00:27:18,504 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/07_login-tree-300/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-23 00:27:18,504 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 00:27:19,877 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:19,877 DEBUG --- stdout --- 2024-06-23 00:27:19,877 DEBUG 2024-06-23 00:27:19,878 DEBUG --- stderr --- 2024-06-23 00:27:19,878 DEBUG Order has been successfully forwarded to overseer 2024-06-23 00:27:19 - INFO: Check if Gatling simulation started successfully 2024-06-23 00:27:19,878 INFO 2024-06-23 00:27:19,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-23 00:27:19,879 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:20,193 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:20,193 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:20,193 DEBUG --- stdout --- 2024-06-23 00:27:20,193 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:20,193 DEBUG --- stderr --- 2024-06-23 00:27:20,193 DEBUG 2024-06-23 00:27:20,193 INFO 2024-06-23 00:27:20,193 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-300/stdout.txt | grep "started..." 2024-06-23 00:27:20,193 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 00:27:20,926 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-23 00:27:26,680 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-23 00:27:26,680 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:26,681 DEBUG --- stdout --- 2024-06-23 00:27:26,681 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 00:27:26,681 DEBUG --- stderr --- 2024-06-23 00:27:26,681 DEBUG ________________________________________________________________________________ [2024-06-23 00:27:26] login-tree-300 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 06s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 07s, 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-23 01:29:36] login-tree-300 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-23 01:29:36,529 INFO 2024-06-23 01:29:36,529 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 01:29:36,529 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:36,853 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:36,853 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:36,853 DEBUG --- stdout --- 2024-06-23 01:29:36,853 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:36,853 DEBUG --- stderr --- 2024-06-23 01:29:36,853 DEBUG 2024-06-23 01:29:36,854 INFO 2024-06-23 01:29:36,854 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-23 01:29:36,854 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 01:29:37,736 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:37,737 DEBUG --- stdout --- 2024-06-23 01:29:37,737 DEBUG 2024-06-23 01:29:37,737 DEBUG --- stderr --- 2024-06-23 01:29:37,737 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-23 01:29:37] login-tree-300 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-23 01:29:37 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/order.json 2024-06-23 01:29:37,739 INFO 2024-06-23 01:29:37,739 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 01:29:37,739 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:38,124 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:38,124 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:38,124 DEBUG --- stdout --- 2024-06-23 01:29:38,125 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:38,125 DEBUG --- stderr --- 2024-06-23 01:29:38,125 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 01:29:38,125 INFO 2024-06-23 01:29:38,125 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 01:29:38,125 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:38,440 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:38,441 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:38,441 DEBUG --- stdout --- 2024-06-23 01:29:38,441 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:38,441 DEBUG --- stderr --- 2024-06-23 01:29:38,441 DEBUG 2024-06-23 01:29:38,441 INFO 2024-06-23 01:29:38,441 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-300/rc.txt" ]; then echo "/results/orders/login-tree-300/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-300/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/rc.txt file found" 2024-06-23 01:29:38,441 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 01:29:39,264 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 01:29:39,264 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:39,264 DEBUG --- stdout --- 2024-06-23 01:29:39,264 DEBUG /results/orders/login-tree-300/rc.txt file found 2024-06-23 01:29:39,264 DEBUG --- stderr --- 2024-06-23 01:29:39,264 DEBUG 2024-06-23 01:29:39,265 INFO 2024-06-23 01:29:39,265 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 01:29:39,265 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:39,570 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:39,570 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:39,570 DEBUG --- stdout --- 2024-06-23 01:29:39,570 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:39,570 DEBUG --- stderr --- 2024-06-23 01:29:39,570 DEBUG 2024-06-23 01:29:39,570 INFO 2024-06-23 01:29:39,571 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-300/rc.txt 2024-06-23 01:29:40,373 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 01:29:40,373 DEBUG --- stdout --- 2024-06-23 01:29:40,373 DEBUG 4 /results/orders/login-tree-300/rc.txt 2024-06-23 01:29:40,373 DEBUG --- stderr --- 2024-06-23 01:29:40,373 DEBUG 2024-06-23 01:29:40,374 INFO - Download file (size 4k) 2024-06-23 01:29:40,374 INFO 2024-06-23 01:29:40,374 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 01:29:40,374 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:40,701 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:40,702 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:40,702 DEBUG --- stdout --- 2024-06-23 01:29:40,702 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:40,702 DEBUG --- stderr --- 2024-06-23 01:29:40,702 DEBUG source : /results/orders/login-tree-300/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/rc.txt 2024-06-23 01:29:40,702 INFO 2024-06-23 01:29:40,702 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 01:29:40,702 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:41,028 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:41,028 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:41,028 DEBUG --- stdout --- 2024-06-23 01:29:41,028 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:41,028 DEBUG --- stderr --- 2024-06-23 01:29:41,028 DEBUG 2024-06-23 01:29:41,029 INFO 2024-06-23 01:29:41,029 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-300/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/rc.txt 2024-06-23 01:29:41,029 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 01:29:41,771 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:41,771 DEBUG --- stdout --- 2024-06-23 01:29:41,771 DEBUG tar: Removing leading `/' from member names 2024-06-23 01:29:41,771 DEBUG --- stderr --- 2024-06-23 01:29:41,771 DEBUG 2024-06-23 01:29:41,771 INFO 2024-06-23 01:29:41,771 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 01:29:41,771 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:42,074 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:42,075 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:42,075 DEBUG --- stdout --- 2024-06-23 01:29:42,075 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:42,075 DEBUG --- stderr --- 2024-06-23 01:29:42,075 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 01:29:42,075 INFO 2024-06-23 01:29:42,075 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 01:29:42,075 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:42,381 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:42,382 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:42,382 DEBUG --- stdout --- 2024-06-23 01:29:42,382 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:42,382 DEBUG --- stderr --- 2024-06-23 01:29:42,382 DEBUG 2024-06-23 01:29:42,383 INFO 2024-06-23 01:29:42,383 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-300/stderr.txt" ]; then echo "/results/orders/login-tree-300/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-300/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/stderr.txt file found" 2024-06-23 01:29:42,383 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 01:29:43,191 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 01:29:43,191 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:43,191 DEBUG --- stdout --- 2024-06-23 01:29:43,191 DEBUG /results/orders/login-tree-300/stderr.txt file found 2024-06-23 01:29:43,191 DEBUG --- stderr --- 2024-06-23 01:29:43,191 DEBUG 2024-06-23 01:29:43,191 INFO 2024-06-23 01:29:43,192 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 01:29:43,192 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:43,507 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:43,507 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:43,507 DEBUG --- stdout --- 2024-06-23 01:29:43,507 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:43,508 DEBUG --- stderr --- 2024-06-23 01:29:43,508 DEBUG 2024-06-23 01:29:43,508 INFO 2024-06-23 01:29:43,508 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-300/stderr.txt 2024-06-23 01:29:44,317 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 01:29:44,318 DEBUG --- stdout --- 2024-06-23 01:29:44,318 DEBUG 0 /results/orders/login-tree-300/stderr.txt 2024-06-23 01:29:44,318 DEBUG --- stderr --- 2024-06-23 01:29:44,318 DEBUG 2024-06-23 01:29:44,318 INFO - Download file (size 0k) 2024-06-23 01:29:44,318 INFO 2024-06-23 01:29:44,318 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 01:29:44,318 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:44,634 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:44,634 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:44,634 DEBUG --- stdout --- 2024-06-23 01:29:44,634 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:44,634 DEBUG --- stderr --- 2024-06-23 01:29:44,634 DEBUG source : /results/orders/login-tree-300/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/stderr.txt 2024-06-23 01:29:44,635 INFO 2024-06-23 01:29:44,635 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 01:29:44,635 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:44,963 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:44,963 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:44,963 DEBUG --- stdout --- 2024-06-23 01:29:44,964 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:44,964 DEBUG --- stderr --- 2024-06-23 01:29:44,964 DEBUG 2024-06-23 01:29:44,964 INFO 2024-06-23 01:29:44,964 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-300/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/stderr.txt 2024-06-23 01:29:44,964 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 01:29:45,703 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:45,703 DEBUG --- stdout --- 2024-06-23 01:29:45,704 DEBUG tar: Removing leading `/' from member names 2024-06-23 01:29:45,704 DEBUG --- stderr --- 2024-06-23 01:29:45,704 DEBUG 2024-06-23 01:29:45,704 INFO 2024-06-23 01:29:45,704 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 01:29:45,704 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:46,015 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:46,015 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:46,015 DEBUG --- stdout --- 2024-06-23 01:29:46,015 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:46,015 DEBUG --- stderr --- 2024-06-23 01:29:46,015 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 01:29:46,015 INFO 2024-06-23 01:29:46,015 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 01:29:46,015 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:46,323 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:46,323 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:46,323 DEBUG --- stdout --- 2024-06-23 01:29:46,323 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:46,323 DEBUG --- stderr --- 2024-06-23 01:29:46,323 DEBUG 2024-06-23 01:29:46,324 INFO 2024-06-23 01:29:46,324 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-300/stdout.txt" ]; then echo "/results/orders/login-tree-300/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-300/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/stdout.txt file found" 2024-06-23 01:29:46,324 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 01:29:47,123 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 01:29:47,123 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:47,124 DEBUG --- stdout --- 2024-06-23 01:29:47,124 DEBUG /results/orders/login-tree-300/stdout.txt file found 2024-06-23 01:29:47,124 DEBUG --- stderr --- 2024-06-23 01:29:47,124 DEBUG 2024-06-23 01:29:47,125 INFO 2024-06-23 01:29:47,125 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 01:29:47,125 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:47,431 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:47,431 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:47,432 DEBUG --- stdout --- 2024-06-23 01:29:47,432 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:47,432 DEBUG --- stderr --- 2024-06-23 01:29:47,432 DEBUG 2024-06-23 01:29:47,432 INFO 2024-06-23 01:29:47,432 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-300/stdout.txt 2024-06-23 01:29:48,253 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 01:29:48,253 DEBUG --- stdout --- 2024-06-23 01:29:48,253 DEBUG 860 /results/orders/login-tree-300/stdout.txt 2024-06-23 01:29:48,253 DEBUG --- stderr --- 2024-06-23 01:29:48,253 DEBUG 2024-06-23 01:29:48,253 INFO - Download file (size 860k) 2024-06-23 01:29:48,254 INFO 2024-06-23 01:29:48,254 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 01:29:48,254 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:48,572 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:48,572 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:48,572 DEBUG --- stdout --- 2024-06-23 01:29:48,572 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:48,573 DEBUG --- stderr --- 2024-06-23 01:29:48,573 DEBUG source : /results/orders/login-tree-300/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/stdout.txt 2024-06-23 01:29:48,573 INFO 2024-06-23 01:29:48,573 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 01:29:48,573 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:48,898 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:48,898 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:48,898 DEBUG --- stdout --- 2024-06-23 01:29:48,898 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:48,898 DEBUG --- stderr --- 2024-06-23 01:29:48,898 DEBUG 2024-06-23 01:29:48,899 INFO 2024-06-23 01:29:48,899 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-300/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/stdout.txt 2024-06-23 01:29:48,899 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 01:29:50,019 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:50,019 DEBUG --- stdout --- 2024-06-23 01:29:50,019 DEBUG tar: Removing leading `/' from member names 2024-06-23 01:29:50,019 DEBUG --- stderr --- 2024-06-23 01:29:50,019 DEBUG ________________________________________________________________________________ [2024-06-23 01:29:50] login-tree-300 show_cmd : Display order command ________________________________________________________________________________ 2024-06-23 01:29:50,020 INFO 2024-06-23 01:29:50,020 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 01:29:50,020 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:50,351 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:50,351 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:50,351 DEBUG --- stdout --- 2024-06-23 01:29:50,352 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:50,352 DEBUG --- stderr --- 2024-06-23 01:29:50,352 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 01:29:50,352 INFO 2024-06-23 01:29:50,352 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 01:29:50,352 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:50,678 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:50,678 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:50,678 DEBUG --- stdout --- 2024-06-23 01:29:50,678 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:50,678 DEBUG --- stderr --- 2024-06-23 01:29:50,678 DEBUG 2024-06-23 01:29:50,679 INFO 2024-06-23 01:29:50,679 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-300/cmd.txt" ]; then echo "/results/orders/login-tree-300/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-300/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/cmd.txt file found" 2024-06-23 01:29:50,679 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 01:29:51,504 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 01:29:51,504 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:51,504 DEBUG --- stdout --- 2024-06-23 01:29:51,504 DEBUG /results/orders/login-tree-300/cmd.txt file found 2024-06-23 01:29:51,504 DEBUG --- stderr --- 2024-06-23 01:29:51,504 DEBUG 2024-06-23 01:29:51,505 INFO 2024-06-23 01:29:51,505 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 01:29:51,505 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:51,824 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:51,824 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:51,825 DEBUG --- stdout --- 2024-06-23 01:29:51,825 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:51,825 DEBUG --- stderr --- 2024-06-23 01:29:51,825 DEBUG 2024-06-23 01:29:51,825 INFO 2024-06-23 01:29:51,825 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-300/cmd.txt 2024-06-23 01:29:52,775 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 01:29:52,775 DEBUG --- stdout --- 2024-06-23 01:29:52,775 DEBUG 4 /results/orders/login-tree-300/cmd.txt 2024-06-23 01:29:52,775 DEBUG --- stderr --- 2024-06-23 01:29:52,775 DEBUG 2024-06-23 01:29:52,775 INFO - Download file (size 4k) 2024-06-23 01:29:52,776 INFO 2024-06-23 01:29:52,776 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 01:29:52,776 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:53,093 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:53,093 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:53,093 DEBUG --- stdout --- 2024-06-23 01:29:53,093 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:53,093 DEBUG --- stderr --- 2024-06-23 01:29:53,093 DEBUG source : /results/orders/login-tree-300/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/cmd.txt 2024-06-23 01:29:53,094 INFO 2024-06-23 01:29:53,094 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 01:29:53,094 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 01:29:53,418 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 01:29:53,419 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:53,419 DEBUG --- stdout --- 2024-06-23 01:29:53,419 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 01:29:53,419 DEBUG --- stderr --- 2024-06-23 01:29:53,419 DEBUG 2024-06-23 01:29:53,419 INFO 2024-06-23 01:29:53,419 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-300/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/cmd.txt 2024-06-23 01:29:53,419 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 01:29:54,146 INFO [loop_until]: OK (rc = 0) 2024-06-23 01:29:54,146 DEBUG --- stdout --- 2024-06-23 01:29:54,146 DEBUG tar: Removing leading `/' from member names 2024-06-23 01:29:54,146 DEBUG --- stderr --- 2024-06-23 01:29:54,146 DEBUG 2024-06-23 01:29:54,147 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/cmd.txt is small (1 lines), printing all lines: 2024-06-23 01:29:54,147 DEBUG ----- output ----- 2024-06-23 01:29:54,147 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-300 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-23 01:29:54,147 INFO ________________________________________________________________________________ [2024-06-23 01:29:54] login-tree-300 show_rc : Display rc order ________________________________________________________________________________ 2024-06-23 01:29:54,147 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/rc.txt is small (1 lines), printing all lines: 2024-06-23 01:29:54,147 DEBUG ----- output ----- 2024-06-23 01:29:54,147 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-23 01:29:54,147 INFO ________________________________________________________________________________ [2024-06-23 01:29:54] login-tree-300 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-23 01:29:54,149 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/07_login-tree-300/stdout.txt is 12958 lines): 2024-06-23 01:29:54,149 DEBUG ----- output ----- 2024-06-23 01:29:54,149 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-23 01:29:54,149 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-23 01:29:54,149 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 01:29:54,149 INFO 2024-06-23 01:29:54,149 INFO ================================================================================ 2024-06-23 01:29:54,149 INFO 2024-06-23 01:27:28 5s elapsed 2024-06-23 01:29:54,150 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 01:29:54,150 INFO > Global (OK=260 KO=0 ) 2024-06-23 01:29:54,150 INFO > restLoginInitiate (OK=124 KO=0 ) 2024-06-23 01:29:54,150 INFO > restLoginUsernamePasswordCallback (OK=118 KO=0 ) 2024-06-23 01:29:54,150 INFO > restloginProgressiveProfileCallback (OK=18 KO=0 ) 2024-06-23 01:29:54,150 INFO 2024-06-23 01:29:54,150 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,150 INFO [- ] 0% 2024-06-23 01:29:54,150 INFO waiting: 1085253 / active: 8 / done: 118 2024-06-23 01:29:54,150 INFO ================================================================================ 2024-06-23 01:29:54,150 INFO 2024-06-23 01:29:54,150 INFO 2024-06-23 01:29:54,150 INFO ================================================================================ 2024-06-23 01:29:54,150 INFO 2024-06-23 01:27:33 10s elapsed 2024-06-23 01:29:54,150 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 01:29:54,150 INFO > Global (OK=1078 KO=0 ) 2024-06-23 01:29:54,150 INFO > restLoginInitiate (OK=497 KO=0 ) 2024-06-23 01:29:54,150 INFO > restLoginUsernamePasswordCallback (OK=486 KO=0 ) 2024-06-23 01:29:54,150 INFO > restloginProgressiveProfileCallback (OK=95 KO=0 ) 2024-06-23 01:29:54,150 INFO 2024-06-23 01:29:54,150 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,150 INFO [- ] 0% 2024-06-23 01:29:54,150 INFO waiting: 1084877 / active: 17 / done: 485 2024-06-23 01:29:54,150 INFO ================================================================================ 2024-06-23 01:29:54,150 INFO 2024-06-23 01:29:54,150 INFO 2024-06-23 01:29:54,150 INFO ================================================================================ 2024-06-23 01:29:54,150 INFO 2024-06-23 01:27:38 15s elapsed 2024-06-23 01:29:54,150 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 01:29:54,150 INFO > Global (OK=2425 KO=0 ) 2024-06-23 01:29:54,150 INFO > restLoginInitiate (OK=1120 KO=0 ) 2024-06-23 01:29:54,151 INFO > restLoginUsernamePasswordCallback (OK=1103 KO=0 ) 2024-06-23 01:29:54,151 INFO > restloginProgressiveProfileCallback (OK=202 KO=0 ) 2024-06-23 01:29:54,151 INFO 2024-06-23 01:29:54,151 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,151 INFO [- ] 0% 2024-06-23 01:29:54,151 INFO waiting: 1084251 / active: 29 / done: 1099 2024-06-23 01:29:54,151 INFO ================================================================================ 2024-06-23 01:29:54,151 INFO 2024-06-23 01:29:54,151 INFO 2024-06-23 01:29:54,151 INFO ================================================================================ 2024-06-23 01:29:54,151 INFO 2024-06-23 01:27:43 20s elapsed 2024-06-23 01:29:54,151 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 01:29:54,151 INFO > Global (OK=4329 KO=0 ) 2024-06-23 01:29:54,151 INFO > restLoginInitiate (OK=1993 KO=0 ) 2024-06-23 01:29:54,151 INFO > restLoginUsernamePasswordCallback (OK=1971 KO=0 ) 2024-06-23 01:29:54,151 INFO > restloginProgressiveProfileCallback (OK=365 KO=0 ) 2024-06-23 01:29:54,151 INFO 2024-06-23 01:29:54,151 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,151 INFO [- ] 0% 2024-06-23 01:29:54,151 INFO waiting: 1083376 / active: 36 / done: 1967 2024-06-23 01:29:54,151 INFO ================================================================================ 2024-06-23 01:29:54,151 INFO 2024-06-23 01:29:54,151 INFO 2024-06-23 01:29:54,151 INFO ================================================================================ 2024-06-23 01:29:54,151 INFO 2024-06-23 01:27:48 25s elapsed 2024-06-23 01:29:54,151 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 01:29:54,151 INFO > Global (OK=6768 KO=0 ) 2024-06-23 01:29:54,151 INFO > restLoginInitiate (OK=3115 KO=0 ) 2024-06-23 01:29:54,151 INFO > restLoginUsernamePasswordCallback (OK=3085 KO=0 ) 2024-06-23 01:29:54,151 INFO > restloginProgressiveProfileCallback (OK=568 KO=0 ) 2024-06-23 01:29:54,151 INFO 2024-06-23 01:29:54,151 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,152 INFO [- ] 0% 2024-06-23 01:29:54,152 INFO waiting: 1082250 / active: 53 / done: 3076 2024-06-23 01:29:54,152 INFO ================================================================================ 2024-06-23 01:29:54,152 INFO 2024-06-23 01:29:54,152 INFO 2024-06-23 01:29:54,152 INFO ================================================================================ 2024-06-23 01:29:54,152 INFO 2024-06-23 01:27:53 30s elapsed 2024-06-23 01:29:54,152 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 01:29:54,152 INFO > Global (OK=9734 KO=0 ) 2024-06-23 01:29:54,152 INFO > restLoginInitiate (OK=4485 KO=0 ) 2024-06-23 01:29:54,152 INFO > restLoginUsernamePasswordCallback (OK=4442 KO=0 ) 2024-06-23 01:29:54,152 INFO > restloginProgressiveProfileCallback (OK=807 KO=0 ) 2024-06-23 01:29:54,152 INFO 2024-06-23 01:29:54,152 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,152 INFO [- ] 0% 2024-06-23 01:29:54,152 INFO waiting: 1080874 / active: 78 / done: 4427 2024-06-23 01:29:54,152 INFO ================================================================================ 2024-06-23 01:29:54,152 INFO 2024-06-23 01:29:54,152 INFO 2024-06-23 01:29:54,152 INFO ================================================================================ 2024-06-23 01:29:54,152 INFO 2024-06-23 01:27:58 35s elapsed 2024-06-23 01:29:54,152 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 01:29:54,152 INFO > Global (OK=13176 KO=0 ) 2024-06-23 01:29:54,152 INFO > restLoginInitiate (OK=6085 KO=0 ) 2024-06-23 01:29:54,152 INFO > restLoginUsernamePasswordCallback (OK=6032 KO=0 ) 2024-06-23 01:29:54,152 INFO > restloginProgressiveProfileCallback (OK=1059 KO=0 ) 2024-06-23 01:29:54,152 INFO 2024-06-23 01:29:54,152 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,152 INFO [- ] 0% 2024-06-23 01:29:54,152 INFO waiting: 1079277 / active: 83 / done: 6019 2024-06-23 01:29:54,152 INFO ================================================================================ 2024-06-23 01:29:54,152 INFO [...] 2024-06-23 01:29:54,156 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,156 INFO [#########################################################################-] 99% 2024-06-23 01:29:54,156 INFO waiting: 4414 / active: 72 / done: 1080893 2024-06-23 01:29:54,156 INFO ================================================================================ 2024-06-23 01:29:54,156 INFO 2024-06-23 01:29:54,156 INFO 2024-06-23 01:29:54,156 INFO ================================================================================ 2024-06-23 01:29:54,156 INFO 2024-06-23 02:27:43 3620s elapsed 2024-06-23 01:29:54,156 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 01:29:54,156 INFO > Global (OK=2315666 KO=18 ) 2024-06-23 01:29:54,157 INFO > restLoginInitiate (OK=1082434 KO=9 ) 2024-06-23 01:29:54,157 INFO > restLoginUsernamePasswordCallback (OK=1082397 KO=9 ) 2024-06-23 01:29:54,157 INFO > restloginProgressiveProfileCallback (OK=150835 KO=0 ) 2024-06-23 01:29:54,157 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 01:29:54,157 INFO > status.find.is(200), but actually found 502 15 (83.33%) 2024-06-23 01:29:54,157 INFO > i.n.c.ConnectTimeoutException: connection timed out: openam-em 2 (11.11%) 2024-06-23 01:29:54,157 INFO a-perf-p1.forgeblocks.com/35.241.59.5:443 2024-06-23 01:29:54,157 INFO > j.n.c.ClosedChannelException 1 ( 5.56%) 2024-06-23 01:29:54,157 INFO 2024-06-23 01:29:54,157 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,157 INFO [#########################################################################-] 99% 2024-06-23 01:29:54,157 INFO waiting: 2922 / active: 43 / done: 1082414 2024-06-23 01:29:54,157 INFO ================================================================================ 2024-06-23 01:29:54,157 INFO 2024-06-23 01:29:54,157 INFO 2024-06-23 01:29:54,157 INFO ================================================================================ 2024-06-23 01:29:54,157 INFO 2024-06-23 02:27:48 3625s elapsed 2024-06-23 01:29:54,157 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 01:29:54,157 INFO > Global (OK=2318749 KO=18 ) 2024-06-23 01:29:54,157 INFO > restLoginInitiate (OK=1083892 KO=9 ) 2024-06-23 01:29:54,157 INFO > restLoginUsernamePasswordCallback (OK=1083858 KO=9 ) 2024-06-23 01:29:54,157 INFO > restloginProgressiveProfileCallback (OK=150999 KO=0 ) 2024-06-23 01:29:54,157 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 01:29:54,157 INFO > status.find.is(200), but actually found 502 15 (83.33%) 2024-06-23 01:29:54,157 INFO > i.n.c.ConnectTimeoutException: connection timed out: openam-em 2 (11.11%) 2024-06-23 01:29:54,157 INFO a-perf-p1.forgeblocks.com/35.241.59.5:443 2024-06-23 01:29:54,157 INFO > j.n.c.ClosedChannelException 1 ( 5.56%) 2024-06-23 01:29:54,157 INFO 2024-06-23 01:29:54,157 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,157 INFO [#########################################################################-] 99% 2024-06-23 01:29:54,157 INFO waiting: 1466 / active: 39 / done: 1083874 2024-06-23 01:29:54,157 INFO ================================================================================ 2024-06-23 01:29:54,157 INFO 2024-06-23 01:29:54,158 INFO 2024-06-23 01:29:54,158 INFO ================================================================================ 2024-06-23 01:29:54,158 INFO 2024-06-23 02:27:53 3630s elapsed 2024-06-23 01:29:54,158 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 01:29:54,158 INFO > Global (OK=2321782 KO=18 ) 2024-06-23 01:29:54,158 INFO > restLoginInitiate (OK=1085358 KO=9 ) 2024-06-23 01:29:54,158 INFO > restLoginUsernamePasswordCallback (OK=1085279 KO=9 ) 2024-06-23 01:29:54,158 INFO > restloginProgressiveProfileCallback (OK=151145 KO=0 ) 2024-06-23 01:29:54,158 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 01:29:54,158 INFO > status.find.is(200), but actually found 502 15 (83.33%) 2024-06-23 01:29:54,158 INFO > i.n.c.ConnectTimeoutException: connection timed out: openam-em 2 (11.11%) 2024-06-23 01:29:54,158 INFO a-perf-p1.forgeblocks.com/35.241.59.5:443 2024-06-23 01:29:54,158 INFO > j.n.c.ClosedChannelException 1 ( 5.56%) 2024-06-23 01:29:54,158 INFO 2024-06-23 01:29:54,158 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,158 INFO [#########################################################################-] 99% 2024-06-23 01:29:54,158 INFO waiting: 0 / active: 88 / done: 1085291 2024-06-23 01:29:54,158 INFO ================================================================================ 2024-06-23 01:29:54,158 INFO 2024-06-23 01:29:54,158 INFO 2024-06-23 01:29:54,158 INFO ================================================================================ 2024-06-23 01:29:54,158 INFO 2024-06-23 02:27:54 3630s elapsed 2024-06-23 01:29:54,158 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 01:29:54,158 INFO > Global (OK=2321892 KO=18 ) 2024-06-23 01:29:54,158 INFO > restLoginInitiate (OK=1085370 KO=9 ) 2024-06-23 01:29:54,158 INFO > restLoginUsernamePasswordCallback (OK=1085361 KO=9 ) 2024-06-23 01:29:54,158 INFO > restloginProgressiveProfileCallback (OK=151161 KO=0 ) 2024-06-23 01:29:54,158 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 01:29:54,158 INFO > status.find.is(200), but actually found 502 15 (83.33%) 2024-06-23 01:29:54,158 INFO > i.n.c.ConnectTimeoutException: connection timed out: openam-em 2 (11.11%) 2024-06-23 01:29:54,158 INFO a-perf-p1.forgeblocks.com/35.241.59.5:443 2024-06-23 01:29:54,158 INFO > j.n.c.ClosedChannelException 1 ( 5.56%) 2024-06-23 01:29:54,159 INFO 2024-06-23 01:29:54,159 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 01:29:54,159 INFO [##########################################################################]100% 2024-06-23 01:29:54,159 INFO waiting: 0 / active: 0 / done: 1085379 2024-06-23 01:29:54,159 INFO ================================================================================ 2024-06-23 01:29:54,159 INFO 2024-06-23 01:29:54,159 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-23 01:29:54,159 INFO 2024-06-23 01:29:54,159 INFO ---------------------- 2024-06-23 01:29:54,159 INFO [23/Jun/2024 02:27:54] Create tar file login-tree-300.tar.gz 2024-06-23 01:29:54,159 INFO ---------------------- 2024-06-23 01:29:54,159 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-23 01:29:54,159 INFO RESULT_FOLDER=login-tree-300 2024-06-23 01:29:54,159 INFO SIMULATION_PATH=login-tree-300/loadtest-20240623002723348 2024-06-23 01:29:54,159 INFO PWD=/results/gatling/login-tree-300/loadtest-20240623002723348 2024-06-23 01:29:54,159 INFO [cmd] tar -czvf /results/gatling//login-tree-300.tar.gz --exclude=simulation.log * 2024-06-23 01:29:54,159 INFO /results/gatling 2024-06-23 01:29:54,159 INFO 2024-06-23 01:29:54,159 INFO ---------------------- 2024-06-23 01:29:54,159 INFO [23/Jun/2024 02:27:54] Update /results/gatling//latest 2024-06-23 01:29:54,159 INFO ---------------------- 2024-06-23 01:29:54,159 INFO 2024-06-23 01:29:54,159 INFO ---------------------- 2024-06-23 01:29:54,159 INFO [23/Jun/2024 02:27:54] Update /results/gatling//latest.tar.gz 2024-06-23 01:29:54,159 INFO ---------------------- 2024-06-23 01:29:54,159 INFO ________________________________________________________________________________ [2024-06-23 01:29:54] login-tree-300 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-23 01:29:54,160 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-300/stderr.txt is small (0 lines), printing all lines: 2024-06-23 01:29:54,160 DEBUG ----- output ----- 2024-06-23 01:29:54,160 DEBUG 2024-06-23 01:29:54,160 INFO ________________________________________________________________________________ [2024-06-23 01:29:54] login-tree-300 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-23 01:29:54] login-tree-300 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-23 01:29:54] login-tree-300 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped