--Task-- name: login-tree-200 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-150'] preceding_task: login-tree-150 options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 200, '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 21:19:23] login-tree-200 pre : N/A ________________________________________________________________________________ 2024-06-22 21:19:23 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-22 21:19:23 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-200/stdout.txt Pod log : /results/orders/login-tree-200 2024-06-22 21:19:23,111 INFO 2024-06-22 21:19:23,111 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:23,111 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:23,415 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:23,415 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:23,415 DEBUG --- stdout --- 2024-06-22 21:19:23,416 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:23,416 DEBUG --- stderr --- 2024-06-22 21:19:23,416 DEBUG ________________________________________________________________________________ [2024-06-22 21:19:23] login-tree-200 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-22 21:19:23,416 INFO 2024-06-22 21:19:23,416 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:23,416 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:23,723 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:23,723 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:23,723 DEBUG --- stdout --- 2024-06-22 21:19:23,724 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:23,724 DEBUG --- stderr --- 2024-06-22 21:19:23,724 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-200 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-22 21:19:23] login-tree-200 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-22 21:19:23,724 INFO 2024-06-22 21:19:23,724 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 21:19:23,724 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:24,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:24,029 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:24,029 DEBUG --- stdout --- 2024-06-22 21:19:24,029 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:24,029 DEBUG --- stderr --- 2024-06-22 21:19:24,029 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/order.json { "name": "login-tree-200", "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-200 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-200", "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=200 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-22 21:19:24] login-tree-200 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 21:19:24,031 INFO 2024-06-22 21:19:24,031 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-22 21:19:24,031 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 21:19:24,278 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 21:19:24,278 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:24,278 DEBUG --- stdout --- 2024-06-22 21:19:24,278 DEBUG WAITING 2024-06-22 21:19:24,278 DEBUG --- stderr --- 2024-06-22 21:19:24,278 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 21:19:24] login-tree-200 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-22 21:19:24,279 INFO 2024-06-22 21:19:24,279 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:24,279 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:24,598 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:24,598 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:24,599 DEBUG --- stdout --- 2024-06-22 21:19:24,599 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:24,599 DEBUG --- stderr --- 2024-06-22 21:19:24,599 DEBUG 2024-06-22 21:19:24,599 INFO 2024-06-22 21:19:24,599 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/04_login-tree-200/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-22 21:19:24,599 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 21:19:25,964 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:25,964 DEBUG --- stdout --- 2024-06-22 21:19:25,964 DEBUG 2024-06-22 21:19:25,965 DEBUG --- stderr --- 2024-06-22 21:19:25,965 DEBUG Order has been successfully forwarded to overseer 2024-06-22 21:19:25 - INFO: Check if Gatling simulation started successfully 2024-06-22 21:19:25,965 INFO 2024-06-22 21:19:25,965 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:25,965 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 21:19:26,272 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 21:19:26,273 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:26,273 DEBUG --- stdout --- 2024-06-22 21:19:26,273 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 21:19:26,273 DEBUG --- stderr --- 2024-06-22 21:19:26,273 DEBUG 2024-06-22 21:19:26,273 INFO 2024-06-22 21:19:26,273 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-200/stdout.txt | grep "started..." 2024-06-22 21:19:26,273 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 21:19:27,003 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-22 21:19:32,755 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-22 21:19:32,756 INFO [loop_until]: OK (rc = 0) 2024-06-22 21:19:32,756 DEBUG --- stdout --- 2024-06-22 21:19:32,756 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 21:19:32,756 DEBUG --- stderr --- 2024-06-22 21:19:32,756 DEBUG ________________________________________________________________________________ [2024-06-22 21:19:32] login-tree-200 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-22 22:21:42] login-tree-200 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-22 22:21:42,887 INFO 2024-06-22 22:21:42,888 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 22:21:42,888 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:43,227 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:43,227 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:43,227 DEBUG --- stdout --- 2024-06-22 22:21:43,227 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:43,227 DEBUG --- stderr --- 2024-06-22 22:21:43,227 DEBUG 2024-06-22 22:21:43,228 INFO 2024-06-22 22:21:43,228 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 22:21:43,228 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 22:21:44,024 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:44,024 DEBUG --- stdout --- 2024-06-22 22:21:44,024 DEBUG 2024-06-22 22:21:44,024 DEBUG --- stderr --- 2024-06-22 22:21:44,024 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-22 22:21:44] login-tree-200 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-22 22:21:44 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/order.json 2024-06-22 22:21:44,025 INFO 2024-06-22 22:21:44,025 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 22:21:44,026 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:44,348 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:44,348 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:44,349 DEBUG --- stdout --- 2024-06-22 22:21:44,349 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:44,349 DEBUG --- stderr --- 2024-06-22 22:21:44,349 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 22:21:44,349 INFO 2024-06-22 22:21:44,349 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 22:21:44,349 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:44,666 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:44,667 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:44,667 DEBUG --- stdout --- 2024-06-22 22:21:44,667 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:44,667 DEBUG --- stderr --- 2024-06-22 22:21:44,667 DEBUG 2024-06-22 22:21:44,668 INFO 2024-06-22 22:21:44,668 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-200/rc.txt" ]; then echo "/results/orders/login-tree-200/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-200/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/rc.txt file found" 2024-06-22 22:21:44,668 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 22:21:45,484 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 22:21:45,484 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:45,484 DEBUG --- stdout --- 2024-06-22 22:21:45,484 DEBUG /results/orders/login-tree-200/rc.txt file found 2024-06-22 22:21:45,484 DEBUG --- stderr --- 2024-06-22 22:21:45,484 DEBUG 2024-06-22 22:21:45,485 INFO 2024-06-22 22:21:45,485 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 22:21:45,485 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:45,802 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:45,802 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:45,802 DEBUG --- stdout --- 2024-06-22 22:21:45,802 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:45,802 DEBUG --- stderr --- 2024-06-22 22:21:45,802 DEBUG 2024-06-22 22:21:45,802 INFO 2024-06-22 22:21:45,803 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-200/rc.txt 2024-06-22 22:21:46,669 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 22:21:46,669 DEBUG --- stdout --- 2024-06-22 22:21:46,669 DEBUG 4 /results/orders/login-tree-200/rc.txt 2024-06-22 22:21:46,669 DEBUG --- stderr --- 2024-06-22 22:21:46,669 DEBUG 2024-06-22 22:21:46,669 INFO - Download file (size 4k) 2024-06-22 22:21:46,669 INFO 2024-06-22 22:21:46,670 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 22:21:46,670 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:46,987 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:46,987 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:46,987 DEBUG --- stdout --- 2024-06-22 22:21:46,988 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:46,988 DEBUG --- stderr --- 2024-06-22 22:21:46,988 DEBUG source : /results/orders/login-tree-200/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/rc.txt 2024-06-22 22:21:46,988 INFO 2024-06-22 22:21:46,988 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 22:21:46,988 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:47,311 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:47,312 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:47,312 DEBUG --- stdout --- 2024-06-22 22:21:47,312 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:47,312 DEBUG --- stderr --- 2024-06-22 22:21:47,312 DEBUG 2024-06-22 22:21:47,312 INFO 2024-06-22 22:21:47,312 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-200/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/rc.txt 2024-06-22 22:21:47,312 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 22:21:48,054 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:48,054 DEBUG --- stdout --- 2024-06-22 22:21:48,055 DEBUG tar: Removing leading `/' from member names 2024-06-22 22:21:48,055 DEBUG --- stderr --- 2024-06-22 22:21:48,055 DEBUG 2024-06-22 22:21:48,055 INFO 2024-06-22 22:21:48,055 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 22:21:48,055 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:48,373 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:48,373 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:48,373 DEBUG --- stdout --- 2024-06-22 22:21:48,374 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:48,374 DEBUG --- stderr --- 2024-06-22 22:21:48,374 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 22:21:48,374 INFO 2024-06-22 22:21:48,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-22 22:21:48,374 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:48,695 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:48,696 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:48,696 DEBUG --- stdout --- 2024-06-22 22:21:48,696 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:48,696 DEBUG --- stderr --- 2024-06-22 22:21:48,696 DEBUG 2024-06-22 22:21:48,696 INFO 2024-06-22 22:21:48,696 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-200/stderr.txt" ]; then echo "/results/orders/login-tree-200/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-200/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/stderr.txt file found" 2024-06-22 22:21:48,696 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 22:21:49,514 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 22:21:49,514 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:49,514 DEBUG --- stdout --- 2024-06-22 22:21:49,515 DEBUG /results/orders/login-tree-200/stderr.txt file found 2024-06-22 22:21:49,515 DEBUG --- stderr --- 2024-06-22 22:21:49,515 DEBUG 2024-06-22 22:21:49,515 INFO 2024-06-22 22:21:49,515 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 22:21:49,515 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:49,841 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:49,842 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:49,843 DEBUG --- stdout --- 2024-06-22 22:21:49,843 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:49,844 DEBUG --- stderr --- 2024-06-22 22:21:49,844 DEBUG 2024-06-22 22:21:49,844 INFO 2024-06-22 22:21:49,844 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-200/stderr.txt 2024-06-22 22:21:50,663 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 22:21:50,664 DEBUG --- stdout --- 2024-06-22 22:21:50,664 DEBUG 0 /results/orders/login-tree-200/stderr.txt 2024-06-22 22:21:50,664 DEBUG --- stderr --- 2024-06-22 22:21:50,664 DEBUG 2024-06-22 22:21:50,664 INFO - Download file (size 0k) 2024-06-22 22:21:50,664 INFO 2024-06-22 22:21:50,664 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 22:21:50,665 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:50,984 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:50,984 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:50,984 DEBUG --- stdout --- 2024-06-22 22:21:50,984 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:50,984 DEBUG --- stderr --- 2024-06-22 22:21:50,984 DEBUG source : /results/orders/login-tree-200/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stderr.txt 2024-06-22 22:21:50,985 INFO 2024-06-22 22:21:50,985 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 22:21:50,985 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:51,288 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:51,289 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:51,289 DEBUG --- stdout --- 2024-06-22 22:21:51,289 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:51,289 DEBUG --- stderr --- 2024-06-22 22:21:51,289 DEBUG 2024-06-22 22:21:51,289 INFO 2024-06-22 22:21:51,289 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-200/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stderr.txt 2024-06-22 22:21:51,289 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 22:21:52,012 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:52,012 DEBUG --- stdout --- 2024-06-22 22:21:52,012 DEBUG tar: Removing leading `/' from member names 2024-06-22 22:21:52,012 DEBUG --- stderr --- 2024-06-22 22:21:52,012 DEBUG 2024-06-22 22:21:52,013 INFO 2024-06-22 22:21:52,013 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 22:21:52,013 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:52,340 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:52,340 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:52,340 DEBUG --- stdout --- 2024-06-22 22:21:52,340 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:52,340 DEBUG --- stderr --- 2024-06-22 22:21:52,340 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 22:21:52,340 INFO 2024-06-22 22:21:52,340 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 22:21:52,340 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:52,642 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:52,642 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:52,642 DEBUG --- stdout --- 2024-06-22 22:21:52,643 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:52,643 DEBUG --- stderr --- 2024-06-22 22:21:52,643 DEBUG 2024-06-22 22:21:52,644 INFO 2024-06-22 22:21:52,644 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-200/stdout.txt" ]; then echo "/results/orders/login-tree-200/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-200/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/stdout.txt file found" 2024-06-22 22:21:52,644 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 22:21:53,448 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 22:21:53,448 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:53,448 DEBUG --- stdout --- 2024-06-22 22:21:53,448 DEBUG /results/orders/login-tree-200/stdout.txt file found 2024-06-22 22:21:53,448 DEBUG --- stderr --- 2024-06-22 22:21:53,448 DEBUG 2024-06-22 22:21:53,448 INFO 2024-06-22 22:21:53,449 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 22:21:53,449 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:53,752 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:53,752 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:53,752 DEBUG --- stdout --- 2024-06-22 22:21:53,752 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:53,752 DEBUG --- stderr --- 2024-06-22 22:21:53,752 DEBUG 2024-06-22 22:21:53,753 INFO 2024-06-22 22:21:53,753 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-200/stdout.txt 2024-06-22 22:21:54,551 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 22:21:54,551 DEBUG --- stdout --- 2024-06-22 22:21:54,551 DEBUG 792 /results/orders/login-tree-200/stdout.txt 2024-06-22 22:21:54,551 DEBUG --- stderr --- 2024-06-22 22:21:54,551 DEBUG 2024-06-22 22:21:54,551 INFO - Download file (size 792k) 2024-06-22 22:21:54,552 INFO 2024-06-22 22:21:54,552 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 22:21:54,552 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:54,858 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:54,859 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:54,860 DEBUG --- stdout --- 2024-06-22 22:21:54,860 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:54,860 DEBUG --- stderr --- 2024-06-22 22:21:54,860 DEBUG source : /results/orders/login-tree-200/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stdout.txt 2024-06-22 22:21:54,861 INFO 2024-06-22 22:21:54,861 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 22:21:54,861 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:55,166 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:55,166 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:55,166 DEBUG --- stdout --- 2024-06-22 22:21:55,166 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:55,166 DEBUG --- stderr --- 2024-06-22 22:21:55,167 DEBUG 2024-06-22 22:21:55,167 INFO 2024-06-22 22:21:55,167 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-200/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stdout.txt 2024-06-22 22:21:55,167 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 22:21:56,231 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:56,231 DEBUG --- stdout --- 2024-06-22 22:21:56,231 DEBUG tar: Removing leading `/' from member names 2024-06-22 22:21:56,231 DEBUG --- stderr --- 2024-06-22 22:21:56,231 DEBUG ________________________________________________________________________________ [2024-06-22 22:21:56] login-tree-200 show_cmd : Display order command ________________________________________________________________________________ 2024-06-22 22:21:56,232 INFO 2024-06-22 22:21:56,232 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 22:21:56,232 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:56,553 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:56,553 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:56,553 DEBUG --- stdout --- 2024-06-22 22:21:56,553 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:56,553 DEBUG --- stderr --- 2024-06-22 22:21:56,553 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 22:21:56,554 INFO 2024-06-22 22:21:56,554 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 22:21:56,554 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:56,866 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:56,866 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:56,866 DEBUG --- stdout --- 2024-06-22 22:21:56,866 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:56,866 DEBUG --- stderr --- 2024-06-22 22:21:56,866 DEBUG 2024-06-22 22:21:56,867 INFO 2024-06-22 22:21:56,867 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-200/cmd.txt" ]; then echo "/results/orders/login-tree-200/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-200/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/cmd.txt file found" 2024-06-22 22:21:56,867 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 22:21:57,679 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 22:21:57,679 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:57,679 DEBUG --- stdout --- 2024-06-22 22:21:57,680 DEBUG /results/orders/login-tree-200/cmd.txt file found 2024-06-22 22:21:57,680 DEBUG --- stderr --- 2024-06-22 22:21:57,680 DEBUG 2024-06-22 22:21:57,680 INFO 2024-06-22 22:21:57,680 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 22:21:57,680 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:57,983 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:57,984 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:57,984 DEBUG --- stdout --- 2024-06-22 22:21:57,984 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:57,984 DEBUG --- stderr --- 2024-06-22 22:21:57,984 DEBUG 2024-06-22 22:21:57,984 INFO 2024-06-22 22:21:57,984 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-200/cmd.txt 2024-06-22 22:21:58,787 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 22:21:58,787 DEBUG --- stdout --- 2024-06-22 22:21:58,787 DEBUG 4 /results/orders/login-tree-200/cmd.txt 2024-06-22 22:21:58,787 DEBUG --- stderr --- 2024-06-22 22:21:58,787 DEBUG 2024-06-22 22:21:58,787 INFO - Download file (size 4k) 2024-06-22 22:21:58,788 INFO 2024-06-22 22:21:58,788 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 22:21:58,788 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:59,103 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:59,103 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:59,103 DEBUG --- stdout --- 2024-06-22 22:21:59,103 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:59,103 DEBUG --- stderr --- 2024-06-22 22:21:59,103 DEBUG source : /results/orders/login-tree-200/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/cmd.txt 2024-06-22 22:21:59,103 INFO 2024-06-22 22:21:59,103 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 22:21:59,104 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 22:21:59,409 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 22:21:59,409 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:21:59,409 DEBUG --- stdout --- 2024-06-22 22:21:59,410 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 22:21:59,410 DEBUG --- stderr --- 2024-06-22 22:21:59,410 DEBUG 2024-06-22 22:21:59,410 INFO 2024-06-22 22:21:59,410 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-200/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/cmd.txt 2024-06-22 22:21:59,410 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 22:22:00,142 INFO [loop_until]: OK (rc = 0) 2024-06-22 22:22:00,142 DEBUG --- stdout --- 2024-06-22 22:22:00,142 DEBUG tar: Removing leading `/' from member names 2024-06-22 22:22:00,142 DEBUG --- stderr --- 2024-06-22 22:22:00,142 DEBUG 2024-06-22 22:22:00,143 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/cmd.txt is small (1 lines), printing all lines: 2024-06-22 22:22:00,143 DEBUG ----- output ----- 2024-06-22 22:22:00,143 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-200 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-22 22:22:00,143 INFO ________________________________________________________________________________ [2024-06-22 22:22:00] login-tree-200 show_rc : Display rc order ________________________________________________________________________________ 2024-06-22 22:22:00,143 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/rc.txt is small (1 lines), printing all lines: 2024-06-22 22:22:00,143 DEBUG ----- output ----- 2024-06-22 22:22:00,143 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-22 22:22:00,143 INFO ________________________________________________________________________________ [2024-06-22 22:22:00] login-tree-200 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-22 22:22:00,145 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/04_login-tree-200/stdout.txt is 12120 lines): 2024-06-22 22:22:00,145 DEBUG ----- output ----- 2024-06-22 22:22:00,146 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-22 22:22:00,146 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-22 22:22:00,146 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-22 22:22:00,146 INFO 2024-06-22 22:22:00,146 INFO ================================================================================ 2024-06-22 22:22:00,146 INFO 2024-06-22 22:19:35 5s elapsed 2024-06-22 22:22:00,146 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,146 INFO > Global (OK=176 KO=0 ) 2024-06-22 22:22:00,146 INFO > restLoginInitiate (OK=82 KO=0 ) 2024-06-22 22:22:00,146 INFO > restLoginUsernamePasswordCallback (OK=79 KO=0 ) 2024-06-22 22:22:00,146 INFO > restloginProgressiveProfileCallback (OK=15 KO=0 ) 2024-06-22 22:22:00,146 INFO 2024-06-22 22:22:00,146 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,146 INFO [- ] 0% 2024-06-22 22:22:00,146 INFO waiting: 722574 / active: 6 / done: 78 2024-06-22 22:22:00,146 INFO ================================================================================ 2024-06-22 22:22:00,146 INFO 2024-06-22 22:22:00,146 INFO 2024-06-22 22:22:00,146 INFO ================================================================================ 2024-06-22 22:22:00,146 INFO 2024-06-22 22:19:40 10s elapsed 2024-06-22 22:22:00,146 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,146 INFO > Global (OK=716 KO=0 ) 2024-06-22 22:22:00,146 INFO > restLoginInitiate (OK=331 KO=0 ) 2024-06-22 22:22:00,146 INFO > restLoginUsernamePasswordCallback (OK=324 KO=0 ) 2024-06-22 22:22:00,146 INFO > restloginProgressiveProfileCallback (OK=61 KO=0 ) 2024-06-22 22:22:00,146 INFO 2024-06-22 22:22:00,146 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,146 INFO [- ] 0% 2024-06-22 22:22:00,146 INFO waiting: 722324 / active: 10 / done: 324 2024-06-22 22:22:00,146 INFO ================================================================================ 2024-06-22 22:22:00,146 INFO 2024-06-22 22:22:00,146 INFO 2024-06-22 22:22:00,146 INFO ================================================================================ 2024-06-22 22:22:00,146 INFO 2024-06-22 22:19:45 15s elapsed 2024-06-22 22:22:00,146 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,147 INFO > Global (OK=1621 KO=0 ) 2024-06-22 22:22:00,147 INFO > restLoginInitiate (OK=747 KO=0 ) 2024-06-22 22:22:00,147 INFO > restLoginUsernamePasswordCallback (OK=736 KO=0 ) 2024-06-22 22:22:00,147 INFO > restloginProgressiveProfileCallback (OK=138 KO=0 ) 2024-06-22 22:22:00,147 INFO 2024-06-22 22:22:00,147 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,147 INFO [- ] 0% 2024-06-22 22:22:00,147 INFO waiting: 721906 / active: 18 / done: 734 2024-06-22 22:22:00,147 INFO ================================================================================ 2024-06-22 22:22:00,147 INFO 2024-06-22 22:22:00,147 INFO 2024-06-22 22:22:00,147 INFO ================================================================================ 2024-06-22 22:22:00,147 INFO 2024-06-22 22:19:50 20s elapsed 2024-06-22 22:22:00,147 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,147 INFO > Global (OK=2879 KO=0 ) 2024-06-22 22:22:00,147 INFO > restLoginInitiate (OK=1329 KO=0 ) 2024-06-22 22:22:00,147 INFO > restLoginUsernamePasswordCallback (OK=1314 KO=0 ) 2024-06-22 22:22:00,147 INFO > restloginProgressiveProfileCallback (OK=236 KO=0 ) 2024-06-22 22:22:00,147 INFO 2024-06-22 22:22:00,147 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,147 INFO [- ] 0% 2024-06-22 22:22:00,147 INFO waiting: 721323 / active: 22 / done: 1313 2024-06-22 22:22:00,147 INFO ================================================================================ 2024-06-22 22:22:00,147 INFO 2024-06-22 22:22:00,147 INFO 2024-06-22 22:22:00,147 INFO ================================================================================ 2024-06-22 22:22:00,147 INFO 2024-06-22 22:19:55 25s elapsed 2024-06-22 22:22:00,147 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,147 INFO > Global (OK=4491 KO=0 ) 2024-06-22 22:22:00,147 INFO > restLoginInitiate (OK=2077 KO=0 ) 2024-06-22 22:22:00,147 INFO > restLoginUsernamePasswordCallback (OK=2057 KO=0 ) 2024-06-22 22:22:00,147 INFO > restloginProgressiveProfileCallback (OK=357 KO=0 ) 2024-06-22 22:22:00,147 INFO 2024-06-22 22:22:00,147 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,147 INFO [- ] 0% 2024-06-22 22:22:00,147 INFO waiting: 720572 / active: 31 / done: 2055 2024-06-22 22:22:00,147 INFO ================================================================================ 2024-06-22 22:22:00,147 INFO 2024-06-22 22:22:00,147 INFO 2024-06-22 22:22:00,147 INFO ================================================================================ 2024-06-22 22:22:00,147 INFO 2024-06-22 22:20:00 30s elapsed 2024-06-22 22:22:00,147 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,148 INFO > Global (OK=6488 KO=0 ) 2024-06-22 22:22:00,148 INFO > restLoginInitiate (OK=2993 KO=0 ) 2024-06-22 22:22:00,148 INFO > restLoginUsernamePasswordCallback (OK=2969 KO=0 ) 2024-06-22 22:22:00,148 INFO > restloginProgressiveProfileCallback (OK=526 KO=0 ) 2024-06-22 22:22:00,148 INFO 2024-06-22 22:22:00,148 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,148 INFO [- ] 0% 2024-06-22 22:22:00,148 INFO waiting: 719655 / active: 37 / done: 2966 2024-06-22 22:22:00,148 INFO ================================================================================ 2024-06-22 22:22:00,148 INFO 2024-06-22 22:22:00,148 INFO 2024-06-22 22:22:00,148 INFO ================================================================================ 2024-06-22 22:22:00,148 INFO 2024-06-22 22:20:05 35s elapsed 2024-06-22 22:22:00,148 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,148 INFO > Global (OK=8622 KO=0 ) 2024-06-22 22:22:00,148 INFO > restLoginInitiate (OK=3973 KO=0 ) 2024-06-22 22:22:00,148 INFO > restLoginUsernamePasswordCallback (OK=3942 KO=0 ) 2024-06-22 22:22:00,148 INFO > restloginProgressiveProfileCallback (OK=707 KO=0 ) 2024-06-22 22:22:00,148 INFO 2024-06-22 22:22:00,148 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,148 INFO [- ] 0% 2024-06-22 22:22:00,148 INFO waiting: 718676 / active: 48 / done: 3934 2024-06-22 22:22:00,148 INFO ================================================================================ 2024-06-22 22:22:00,148 INFO [...] 2024-06-22 22:22:00,151 INFO 2024-06-22 22:22:00,151 INFO 2024-06-22 22:22:00,151 INFO ================================================================================ 2024-06-22 22:22:00,151 INFO 2024-06-22 23:19:45 3615s elapsed 2024-06-22 22:22:00,151 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,151 INFO > Global (OK=1601802 KO=11 ) 2024-06-22 22:22:00,151 INFO > restLoginInitiate (OK=719644 KO=7 ) 2024-06-22 22:22:00,152 INFO > restLoginUsernamePasswordCallback (OK=719616 KO=2 ) 2024-06-22 22:22:00,152 INFO > restloginProgressiveProfileCallback (OK=162542 KO=2 ) 2024-06-22 22:22:00,152 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 22:22:00,152 INFO > status.find.is(200), but actually found 502 11 (100.0%) 2024-06-22 22:22:00,152 INFO 2024-06-22 22:22:00,152 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,152 INFO [#########################################################################-] 99% 2024-06-22 22:22:00,152 INFO waiting: 2997 / active: 46 / done: 719615 2024-06-22 22:22:00,152 INFO ================================================================================ 2024-06-22 22:22:00,152 INFO 2024-06-22 22:22:00,152 INFO 2024-06-22 22:22:00,152 INFO ================================================================================ 2024-06-22 22:22:00,152 INFO 2024-06-22 23:19:50 3620s elapsed 2024-06-22 22:22:00,152 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,152 INFO > Global (OK=1604117 KO=11 ) 2024-06-22 22:22:00,152 INFO > restLoginInitiate (OK=720667 KO=7 ) 2024-06-22 22:22:00,152 INFO > restLoginUsernamePasswordCallback (OK=720637 KO=2 ) 2024-06-22 22:22:00,152 INFO > restloginProgressiveProfileCallback (OK=162813 KO=2 ) 2024-06-22 22:22:00,152 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 22:22:00,152 INFO > status.find.is(200), but actually found 502 11 (100.0%) 2024-06-22 22:22:00,152 INFO 2024-06-22 22:22:00,152 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,152 INFO [#########################################################################-] 99% 2024-06-22 22:22:00,152 INFO waiting: 1973 / active: 54 / done: 720631 2024-06-22 22:22:00,152 INFO ================================================================================ 2024-06-22 22:22:00,152 INFO 2024-06-22 22:22:00,152 INFO 2024-06-22 22:22:00,152 INFO ================================================================================ 2024-06-22 22:22:00,152 INFO 2024-06-22 23:19:55 3625s elapsed 2024-06-22 22:22:00,152 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,152 INFO > Global (OK=1606346 KO=11 ) 2024-06-22 22:22:00,152 INFO > restLoginInitiate (OK=721652 KO=7 ) 2024-06-22 22:22:00,152 INFO > restLoginUsernamePasswordCallback (OK=721631 KO=2 ) 2024-06-22 22:22:00,152 INFO > restloginProgressiveProfileCallback (OK=163063 KO=2 ) 2024-06-22 22:22:00,152 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 22:22:00,152 INFO > status.find.is(200), but actually found 502 11 (100.0%) 2024-06-22 22:22:00,152 INFO 2024-06-22 22:22:00,152 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,152 INFO [#########################################################################-] 99% 2024-06-22 22:22:00,152 INFO waiting: 991 / active: 34 / done: 721633 2024-06-22 22:22:00,153 INFO ================================================================================ 2024-06-22 22:22:00,153 INFO 2024-06-22 22:22:00,153 INFO 2024-06-22 22:22:00,153 INFO ================================================================================ 2024-06-22 22:22:00,153 INFO 2024-06-22 23:20:00 3630s elapsed 2024-06-22 22:22:00,153 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,153 INFO > Global (OK=1608578 KO=11 ) 2024-06-22 22:22:00,153 INFO > restLoginInitiate (OK=722646 KO=7 ) 2024-06-22 22:22:00,153 INFO > restLoginUsernamePasswordCallback (OK=722619 KO=2 ) 2024-06-22 22:22:00,153 INFO > restloginProgressiveProfileCallback (OK=163313 KO=2 ) 2024-06-22 22:22:00,153 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 22:22:00,153 INFO > status.find.is(200), but actually found 502 11 (100.0%) 2024-06-22 22:22:00,153 INFO 2024-06-22 22:22:00,153 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,153 INFO [#########################################################################-] 99% 2024-06-22 22:22:00,153 INFO waiting: 0 / active: 34 / done: 722624 2024-06-22 22:22:00,153 INFO ================================================================================ 2024-06-22 22:22:00,153 INFO 2024-06-22 22:22:00,153 INFO 2024-06-22 22:22:00,153 INFO ================================================================================ 2024-06-22 22:22:00,153 INFO 2024-06-22 23:20:00 3630s elapsed 2024-06-22 22:22:00,153 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 22:22:00,153 INFO > Global (OK=1608629 KO=11 ) 2024-06-22 22:22:00,153 INFO > restLoginInitiate (OK=722651 KO=7 ) 2024-06-22 22:22:00,153 INFO > restLoginUsernamePasswordCallback (OK=722649 KO=2 ) 2024-06-22 22:22:00,153 INFO > restloginProgressiveProfileCallback (OK=163329 KO=2 ) 2024-06-22 22:22:00,153 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 22:22:00,153 INFO > status.find.is(200), but actually found 502 11 (100.0%) 2024-06-22 22:22:00,153 INFO 2024-06-22 22:22:00,153 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 22:22:00,153 INFO [##########################################################################]100% 2024-06-22 22:22:00,153 INFO waiting: 0 / active: 0 / done: 722658 2024-06-22 22:22:00,153 INFO ================================================================================ 2024-06-22 22:22:00,153 INFO 2024-06-22 22:22:00,153 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-22 22:22:00,153 INFO 2024-06-22 22:22:00,153 INFO ---------------------- 2024-06-22 22:22:00,153 INFO [22/Jun/2024 23:20:00] Create tar file login-tree-200.tar.gz 2024-06-22 22:22:00,153 INFO ---------------------- 2024-06-22 22:22:00,153 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-22 22:22:00,154 INFO RESULT_FOLDER=login-tree-200 2024-06-22 22:22:00,154 INFO SIMULATION_PATH=login-tree-200/loadtest-20240622211929479 2024-06-22 22:22:00,154 INFO PWD=/results/gatling/login-tree-200/loadtest-20240622211929479 2024-06-22 22:22:00,154 INFO [cmd] tar -czvf /results/gatling//login-tree-200.tar.gz --exclude=simulation.log * 2024-06-22 22:22:00,154 INFO /results/gatling 2024-06-22 22:22:00,154 INFO 2024-06-22 22:22:00,154 INFO ---------------------- 2024-06-22 22:22:00,154 INFO [22/Jun/2024 23:20:00] Update /results/gatling//latest 2024-06-22 22:22:00,154 INFO ---------------------- 2024-06-22 22:22:00,154 INFO 2024-06-22 22:22:00,154 INFO ---------------------- 2024-06-22 22:22:00,154 INFO [22/Jun/2024 23:20:00] Update /results/gatling//latest.tar.gz 2024-06-22 22:22:00,154 INFO ---------------------- 2024-06-22 22:22:00,154 INFO ________________________________________________________________________________ [2024-06-22 22:22:00] login-tree-200 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-22 22:22:00,154 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stderr.txt is small (0 lines), printing all lines: 2024-06-22 22:22:00,154 DEBUG ----- output ----- 2024-06-22 22:22:00,154 DEBUG 2024-06-22 22:22:00,154 INFO ________________________________________________________________________________ [2024-06-22 22:22:00] login-tree-200 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 22:22:00] login-tree-200 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-22 22:22:00] login-tree-200 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped