--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-100'] preceding_task: login-tree-100 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 200} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-24 21:59:10] login-tree-200 pre : N/A ________________________________________________________________________________ [2024-05-24 21:59:10] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-24 21:59:10] - 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-05-24 21:59:10,864 INFO 2024-05-24 21:59:10,864 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 21:59:10,864 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:11,192 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:11,192 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:11,192 DEBUG --- stdout --- 2024-05-24 21:59:11,192 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:11,192 DEBUG --- stderr --- 2024-05-24 21:59:11,192 DEBUG ________________________________________________________________________________ [2024-05-24 21:59:11] login-tree-200 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-24 21:59:11,193 INFO 2024-05-24 21:59:11,193 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-05-24 21:59:11,193 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:11,500 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:11,500 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:11,500 DEBUG --- stdout --- 2024-05-24 21:59:11,500 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:11,500 DEBUG --- stderr --- 2024-05-24 21:59:11,500 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-3580" --results-folder /results/gatling/login-tree-200 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-24 21:59:11] login-tree-200 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-24 21:59:11,501 INFO 2024-05-24 21:59:11,501 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-05-24 21:59:11,501 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:11,814 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:11,814 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:11,814 DEBUG --- stdout --- 2024-05-24 21:59:11,815 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:11,815 DEBUG --- stderr --- 2024-05-24 21:59:11,815 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_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-3580\" --results-folder /results/gatling/login-tree-200 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-200", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-3580.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=9537f3c44bdb077 -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-05-24 21:59:11] 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-05-24 21:59:11,817 INFO 2024-05-24 21:59:11,817 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-24 21:59:11,817 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-24 21:59:12,069 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 21:59:12,070 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:12,070 DEBUG --- stdout --- 2024-05-24 21:59:12,070 DEBUG WAITING 2024-05-24 21:59:12,070 DEBUG --- stderr --- 2024-05-24 21:59:12,070 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-05-24 21:59:12] login-tree-200 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-24 21:59:12,070 INFO 2024-05-24 21:59:12,071 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-05-24 21:59:12,071 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:12,380 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:12,380 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:12,380 DEBUG --- stdout --- 2024-05-24 21:59:12,380 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:12,380 DEBUG --- stderr --- 2024-05-24 21:59:12,380 DEBUG 2024-05-24 21:59:12,380 INFO 2024-05-24 21:59:12,381 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-24 21:59:12,381 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 21:59:13,758 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:13,758 DEBUG --- stdout --- 2024-05-24 21:59:13,758 DEBUG 2024-05-24 21:59:13,758 DEBUG --- stderr --- 2024-05-24 21:59:13,758 DEBUG Order has been successfully forwarded to overseer [2024-05-24 21:59:13] - INFO: Check if Gatling simulation started successfully 2024-05-24 21:59:13,759 INFO 2024-05-24 21:59:13,759 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-05-24 21:59:13,759 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:14,066 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:14,067 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:14,067 DEBUG --- stdout --- 2024-05-24 21:59:14,067 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:14,067 DEBUG --- stderr --- 2024-05-24 21:59:14,067 DEBUG 2024-05-24 21:59:14,067 INFO 2024-05-24 21:59:14,067 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 -- head -n 25 /results/orders/login-tree-200/stdout.txt | grep "started..." 2024-05-24 21:59:14,067 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-24 21:59:14,803 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-24 21:59:20,546 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-05-24 21:59:20,546 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:20,546 DEBUG --- stdout --- 2024-05-24 21:59:20,546 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Simulation com.forgerock.gatling.Loadtest started... 2024-05-24 21:59:20,546 DEBUG --- stderr --- 2024-05-24 21:59:20,546 DEBUG ________________________________________________________________________________ [2024-05-24 21:59:20] 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 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, 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-05-24 23:01:30] login-tree-200 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-24 23:01:30,806 INFO 2024-05-24 23:01:30,807 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-05-24 23:01:30,807 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:31,141 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:31,141 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:31,141 DEBUG --- stdout --- 2024-05-24 23:01:31,141 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:31,141 DEBUG --- stderr --- 2024-05-24 23:01:31,141 DEBUG 2024-05-24 23:01:31,142 INFO 2024-05-24 23:01:31,142 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 -- rm -f /results/orders/order.json 2024-05-24 23:01:31,142 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 23:01:31,926 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:31,926 DEBUG --- stdout --- 2024-05-24 23:01:31,926 DEBUG 2024-05-24 23:01:31,926 DEBUG --- stderr --- 2024-05-24 23:01:31,926 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-24 23:01:31] login-tree-200 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-24 23:01:31] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/order.json 2024-05-24 23:01:31,928 INFO 2024-05-24 23:01:31,928 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 23:01:31,928 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:32,241 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:32,243 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:32,243 DEBUG --- stdout --- 2024-05-24 23:01:32,243 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:32,243 DEBUG --- stderr --- 2024-05-24 23:01:32,243 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 23:01:32,244 INFO 2024-05-24 23:01:32,244 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-05-24 23:01:32,244 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:32,549 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:32,549 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:32,549 DEBUG --- stdout --- 2024-05-24 23:01:32,549 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:32,549 DEBUG --- stderr --- 2024-05-24 23:01:32,549 DEBUG 2024-05-24 23:01:32,550 INFO 2024-05-24 23:01:32,550 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --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-05-24 23:01:32,550 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 23:01:33,356 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 23:01:33,357 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:33,357 DEBUG --- stdout --- 2024-05-24 23:01:33,357 DEBUG /results/orders/login-tree-200/rc.txt file found 2024-05-24 23:01:33,357 DEBUG --- stderr --- 2024-05-24 23:01:33,357 DEBUG 2024-05-24 23:01:33,358 INFO 2024-05-24 23:01:33,358 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 23:01:33,358 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:33,726 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:33,726 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:33,726 DEBUG --- stdout --- 2024-05-24 23:01:33,726 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:33,726 DEBUG --- stderr --- 2024-05-24 23:01:33,726 DEBUG 2024-05-24 23:01:33,726 INFO 2024-05-24 23:01:33,727 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-200/rc.txt 2024-05-24 23:01:34,532 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 23:01:34,532 DEBUG --- stdout --- 2024-05-24 23:01:34,532 DEBUG 4 /results/orders/login-tree-200/rc.txt 2024-05-24 23:01:34,532 DEBUG --- stderr --- 2024-05-24 23:01:34,532 DEBUG 2024-05-24 23:01:34,533 INFO - Download file (size 4k) 2024-05-24 23:01:34,533 INFO 2024-05-24 23:01:34,533 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-05-24 23:01:34,533 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:34,855 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:34,855 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:34,855 DEBUG --- stdout --- 2024-05-24 23:01:34,855 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:34,855 DEBUG --- stderr --- 2024-05-24 23:01:34,856 DEBUG source : /results/orders/login-tree-200/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/rc.txt 2024-05-24 23:01:34,856 INFO 2024-05-24 23:01:34,856 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-05-24 23:01:34,856 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:35,166 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:35,166 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:35,166 DEBUG --- stdout --- 2024-05-24 23:01:35,166 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:35,166 DEBUG --- stderr --- 2024-05-24 23:01:35,166 DEBUG 2024-05-24 23:01:35,167 INFO 2024-05-24 23:01:35,167 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-200/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/rc.txt 2024-05-24 23:01:35,167 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 23:01:35,898 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:35,898 DEBUG --- stdout --- 2024-05-24 23:01:35,898 DEBUG tar: Removing leading `/' from member names 2024-05-24 23:01:35,898 DEBUG --- stderr --- 2024-05-24 23:01:35,898 DEBUG 2024-05-24 23:01:35,899 INFO 2024-05-24 23:01:35,899 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-05-24 23:01:35,899 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:36,204 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:36,204 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:36,204 DEBUG --- stdout --- 2024-05-24 23:01:36,204 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:36,204 DEBUG --- stderr --- 2024-05-24 23:01:36,204 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 23:01:36,204 INFO 2024-05-24 23:01:36,204 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-05-24 23:01:36,204 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:36,506 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:36,506 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:36,506 DEBUG --- stdout --- 2024-05-24 23:01:36,506 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:36,506 DEBUG --- stderr --- 2024-05-24 23:01:36,506 DEBUG 2024-05-24 23:01:36,507 INFO 2024-05-24 23:01:36,507 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --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-05-24 23:01:36,507 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 23:01:37,321 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 23:01:37,322 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:37,322 DEBUG --- stdout --- 2024-05-24 23:01:37,322 DEBUG /results/orders/login-tree-200/stderr.txt file found 2024-05-24 23:01:37,322 DEBUG --- stderr --- 2024-05-24 23:01:37,322 DEBUG 2024-05-24 23:01:37,323 INFO 2024-05-24 23:01:37,323 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-05-24 23:01:37,323 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:37,633 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:37,633 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:37,633 DEBUG --- stdout --- 2024-05-24 23:01:37,634 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:37,634 DEBUG --- stderr --- 2024-05-24 23:01:37,634 DEBUG 2024-05-24 23:01:37,634 INFO 2024-05-24 23:01:37,634 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-200/stderr.txt 2024-05-24 23:01:38,467 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 23:01:38,468 DEBUG --- stdout --- 2024-05-24 23:01:38,468 DEBUG 0 /results/orders/login-tree-200/stderr.txt 2024-05-24 23:01:38,468 DEBUG --- stderr --- 2024-05-24 23:01:38,468 DEBUG 2024-05-24 23:01:38,468 INFO - Download file (size 0k) 2024-05-24 23:01:38,468 INFO 2024-05-24 23:01:38,468 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 23:01:38,468 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:38,772 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:38,772 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:38,772 DEBUG --- stdout --- 2024-05-24 23:01:38,772 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:38,772 DEBUG --- stderr --- 2024-05-24 23:01:38,772 DEBUG source : /results/orders/login-tree-200/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/stderr.txt 2024-05-24 23:01:38,773 INFO 2024-05-24 23:01:38,773 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-05-24 23:01:38,773 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:39,084 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:39,084 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:39,084 DEBUG --- stdout --- 2024-05-24 23:01:39,084 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:39,084 DEBUG --- stderr --- 2024-05-24 23:01:39,084 DEBUG 2024-05-24 23:01:39,084 INFO 2024-05-24 23:01:39,084 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-200/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/stderr.txt 2024-05-24 23:01:39,085 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 23:01:39,814 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:39,814 DEBUG --- stdout --- 2024-05-24 23:01:39,814 DEBUG tar: Removing leading `/' from member names 2024-05-24 23:01:39,814 DEBUG --- stderr --- 2024-05-24 23:01:39,814 DEBUG 2024-05-24 23:01:39,815 INFO 2024-05-24 23:01:39,815 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-05-24 23:01:39,815 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:40,121 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:40,122 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:40,122 DEBUG --- stdout --- 2024-05-24 23:01:40,122 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:40,122 DEBUG --- stderr --- 2024-05-24 23:01:40,122 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 23:01:40,122 INFO 2024-05-24 23:01:40,122 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-05-24 23:01:40,122 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:40,431 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:40,431 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:40,431 DEBUG --- stdout --- 2024-05-24 23:01:40,431 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:40,431 DEBUG --- stderr --- 2024-05-24 23:01:40,431 DEBUG 2024-05-24 23:01:40,432 INFO 2024-05-24 23:01:40,432 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --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-05-24 23:01:40,432 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 23:01:41,237 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 23:01:41,237 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:41,238 DEBUG --- stdout --- 2024-05-24 23:01:41,238 DEBUG /results/orders/login-tree-200/stdout.txt file found 2024-05-24 23:01:41,238 DEBUG --- stderr --- 2024-05-24 23:01:41,238 DEBUG 2024-05-24 23:01:41,238 INFO 2024-05-24 23:01:41,238 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-05-24 23:01:41,238 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:41,551 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:41,551 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:41,551 DEBUG --- stdout --- 2024-05-24 23:01:41,551 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:41,551 DEBUG --- stderr --- 2024-05-24 23:01:41,551 DEBUG 2024-05-24 23:01:41,551 INFO 2024-05-24 23:01:41,551 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-200/stdout.txt 2024-05-24 23:01:42,345 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 23:01:42,346 DEBUG --- stdout --- 2024-05-24 23:01:42,347 DEBUG 672 /results/orders/login-tree-200/stdout.txt 2024-05-24 23:01:42,347 DEBUG --- stderr --- 2024-05-24 23:01:42,347 DEBUG 2024-05-24 23:01:42,347 INFO - Download file (size 672k) 2024-05-24 23:01:42,347 INFO 2024-05-24 23:01:42,348 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-05-24 23:01:42,348 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:42,659 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:42,659 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:42,659 DEBUG --- stdout --- 2024-05-24 23:01:42,659 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:42,659 DEBUG --- stderr --- 2024-05-24 23:01:42,659 DEBUG source : /results/orders/login-tree-200/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/stdout.txt 2024-05-24 23:01:42,660 INFO 2024-05-24 23:01:42,660 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-05-24 23:01:42,660 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:42,966 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:42,967 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:42,967 DEBUG --- stdout --- 2024-05-24 23:01:42,967 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:42,967 DEBUG --- stderr --- 2024-05-24 23:01:42,967 DEBUG 2024-05-24 23:01:42,967 INFO 2024-05-24 23:01:42,967 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-200/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/stdout.txt 2024-05-24 23:01:42,967 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 23:01:44,031 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:44,031 DEBUG --- stdout --- 2024-05-24 23:01:44,031 DEBUG tar: Removing leading `/' from member names 2024-05-24 23:01:44,031 DEBUG --- stderr --- 2024-05-24 23:01:44,031 DEBUG ________________________________________________________________________________ [2024-05-24 23:01:44] login-tree-200 show_cmd : Display order command ________________________________________________________________________________ 2024-05-24 23:01:44,032 INFO 2024-05-24 23:01:44,032 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-05-24 23:01:44,032 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:44,345 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:44,345 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:44,345 DEBUG --- stdout --- 2024-05-24 23:01:44,345 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:44,345 DEBUG --- stderr --- 2024-05-24 23:01:44,345 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 23:01:44,346 INFO 2024-05-24 23:01:44,346 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 23:01:44,346 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:44,657 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:44,657 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:44,658 DEBUG --- stdout --- 2024-05-24 23:01:44,658 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:44,658 DEBUG --- stderr --- 2024-05-24 23:01:44,658 DEBUG 2024-05-24 23:01:44,658 INFO 2024-05-24 23:01:44,658 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --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-05-24 23:01:44,658 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 23:01:45,468 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 23:01:45,468 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:45,468 DEBUG --- stdout --- 2024-05-24 23:01:45,468 DEBUG /results/orders/login-tree-200/cmd.txt file found 2024-05-24 23:01:45,468 DEBUG --- stderr --- 2024-05-24 23:01:45,468 DEBUG 2024-05-24 23:01:45,469 INFO 2024-05-24 23:01:45,469 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-05-24 23:01:45,469 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:45,779 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:45,779 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:45,779 DEBUG --- stdout --- 2024-05-24 23:01:45,779 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:45,780 DEBUG --- stderr --- 2024-05-24 23:01:45,780 DEBUG 2024-05-24 23:01:45,780 INFO 2024-05-24 23:01:45,780 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-200/cmd.txt 2024-05-24 23:01:46,591 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 23:01:46,591 DEBUG --- stdout --- 2024-05-24 23:01:46,591 DEBUG 4 /results/orders/login-tree-200/cmd.txt 2024-05-24 23:01:46,591 DEBUG --- stderr --- 2024-05-24 23:01:46,591 DEBUG 2024-05-24 23:01:46,591 INFO - Download file (size 4k) 2024-05-24 23:01:46,592 INFO 2024-05-24 23:01:46,592 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-05-24 23:01:46,592 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:46,894 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:46,894 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:46,894 DEBUG --- stdout --- 2024-05-24 23:01:46,894 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:46,894 DEBUG --- stderr --- 2024-05-24 23:01:46,894 DEBUG source : /results/orders/login-tree-200/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/cmd.txt 2024-05-24 23:01:46,895 INFO 2024-05-24 23:01:46,895 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-05-24 23:01:46,895 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:47,214 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:47,214 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:47,214 DEBUG --- stdout --- 2024-05-24 23:01:47,214 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:47,214 DEBUG --- stderr --- 2024-05-24 23:01:47,214 DEBUG 2024-05-24 23:01:47,214 INFO 2024-05-24 23:01:47,214 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-200/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/cmd.txt 2024-05-24 23:01:47,215 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 23:01:47,941 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:47,941 DEBUG --- stdout --- 2024-05-24 23:01:47,941 DEBUG tar: Removing leading `/' from member names 2024-05-24 23:01:47,941 DEBUG --- stderr --- 2024-05-24 23:01:47,941 DEBUG 2024-05-24 23:01:47,942 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/cmd.txt is small (1 lines), printing all lines: 2024-05-24 23:01:47,942 DEBUG ----- output ----- 2024-05-24 23:01:47,942 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-3580" --results-folder /results/gatling/login-tree-200 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-24 23:01:47,942 INFO ________________________________________________________________________________ [2024-05-24 23:01:47] login-tree-200 show_rc : Display rc order ________________________________________________________________________________ 2024-05-24 23:01:47,943 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/rc.txt is small (1 lines), printing all lines: 2024-05-24 23:01:47,943 DEBUG ----- output ----- 2024-05-24 23:01:47,943 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-24 23:01:47,943 INFO ________________________________________________________________________________ [2024-05-24 23:01:47] login-tree-200 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-24 23:01:47,945 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/stdout.txt is 10859 lines): 2024-05-24 23:01:47,945 DEBUG ----- output ----- 2024-05-24 23:01:47,945 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-24 23:01:47,945 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-24 23:01:47,945 INFO 2024-05-24 23:01:47,945 INFO ================================================================================ 2024-05-24 23:01:47,945 INFO 2024-05-24 22:59:22 5s elapsed 2024-05-24 23:01:47,945 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,945 INFO > Global (OK=167 KO=0 ) 2024-05-24 23:01:47,945 INFO > restLoginInitiate (OK=84 KO=0 ) 2024-05-24 23:01:47,945 INFO > restLoginUsernamePasswordCallback (OK=83 KO=0 ) 2024-05-24 23:01:47,945 INFO 2024-05-24 23:01:47,945 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,945 INFO [- ] 0% 2024-05-24 23:01:47,945 INFO waiting: 722042 / active: 1 / done: 83 2024-05-24 23:01:47,945 INFO ================================================================================ 2024-05-24 23:01:47,945 INFO 2024-05-24 23:01:47,945 INFO 2024-05-24 23:01:47,945 INFO ================================================================================ 2024-05-24 23:01:47,945 INFO 2024-05-24 22:59:27 10s elapsed 2024-05-24 23:01:47,945 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,945 INFO > Global (OK=666 KO=0 ) 2024-05-24 23:01:47,945 INFO > restLoginInitiate (OK=334 KO=0 ) 2024-05-24 23:01:47,945 INFO > restLoginUsernamePasswordCallback (OK=332 KO=0 ) 2024-05-24 23:01:47,945 INFO 2024-05-24 23:01:47,945 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,945 INFO [- ] 0% 2024-05-24 23:01:47,945 INFO waiting: 721791 / active: 3 / done: 332 2024-05-24 23:01:47,945 INFO ================================================================================ 2024-05-24 23:01:47,945 INFO 2024-05-24 23:01:47,945 INFO 2024-05-24 23:01:47,945 INFO ================================================================================ 2024-05-24 23:01:47,945 INFO 2024-05-24 22:59:32 15s elapsed 2024-05-24 23:01:47,945 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,945 INFO > Global (OK=1500 KO=0 ) 2024-05-24 23:01:47,945 INFO > restLoginInitiate (OK=751 KO=0 ) 2024-05-24 23:01:47,945 INFO > restLoginUsernamePasswordCallback (OK=749 KO=0 ) 2024-05-24 23:01:47,946 INFO 2024-05-24 23:01:47,946 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,946 INFO [- ] 0% 2024-05-24 23:01:47,946 INFO waiting: 721374 / active: 3 / done: 749 2024-05-24 23:01:47,946 INFO ================================================================================ 2024-05-24 23:01:47,946 INFO 2024-05-24 23:01:47,946 INFO 2024-05-24 23:01:47,946 INFO ================================================================================ 2024-05-24 23:01:47,946 INFO 2024-05-24 22:59:37 20s elapsed 2024-05-24 23:01:47,946 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,946 INFO > Global (OK=2666 KO=0 ) 2024-05-24 23:01:47,946 INFO > restLoginInitiate (OK=1335 KO=0 ) 2024-05-24 23:01:47,946 INFO > restLoginUsernamePasswordCallback (OK=1331 KO=0 ) 2024-05-24 23:01:47,946 INFO 2024-05-24 23:01:47,946 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,946 INFO [- ] 0% 2024-05-24 23:01:47,946 INFO waiting: 720790 / active: 5 / done: 1331 2024-05-24 23:01:47,946 INFO ================================================================================ 2024-05-24 23:01:47,946 INFO 2024-05-24 23:01:47,946 INFO 2024-05-24 23:01:47,946 INFO ================================================================================ 2024-05-24 23:01:47,946 INFO 2024-05-24 22:59:42 25s elapsed 2024-05-24 23:01:47,946 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,946 INFO > Global (OK=4166 KO=0 ) 2024-05-24 23:01:47,946 INFO > restLoginInitiate (OK=2085 KO=0 ) 2024-05-24 23:01:47,946 INFO > restLoginUsernamePasswordCallback (OK=2081 KO=0 ) 2024-05-24 23:01:47,946 INFO 2024-05-24 23:01:47,946 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,946 INFO [- ] 0% 2024-05-24 23:01:47,946 INFO waiting: 720040 / active: 5 / done: 2081 2024-05-24 23:01:47,946 INFO ================================================================================ 2024-05-24 23:01:47,946 INFO 2024-05-24 23:01:47,946 INFO 2024-05-24 23:01:47,946 INFO ================================================================================ 2024-05-24 23:01:47,946 INFO 2024-05-24 22:59:47 30s elapsed 2024-05-24 23:01:47,946 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,946 INFO > Global (OK=5995 KO=0 ) 2024-05-24 23:01:47,946 INFO > restLoginInitiate (OK=2998 KO=0 ) 2024-05-24 23:01:47,947 INFO > restLoginUsernamePasswordCallback (OK=2997 KO=0 ) 2024-05-24 23:01:47,947 INFO 2024-05-24 23:01:47,947 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,947 INFO [- ] 0% 2024-05-24 23:01:47,947 INFO waiting: 719119 / active: 10 / done: 2997 2024-05-24 23:01:47,947 INFO ================================================================================ 2024-05-24 23:01:47,947 INFO 2024-05-24 23:01:47,947 INFO 2024-05-24 23:01:47,947 INFO ================================================================================ 2024-05-24 23:01:47,947 INFO 2024-05-24 22:59:52 35s elapsed 2024-05-24 23:01:47,947 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,947 INFO > Global (OK=8002 KO=0 ) 2024-05-24 23:01:47,947 INFO > restLoginInitiate (OK=4003 KO=0 ) 2024-05-24 23:01:47,947 INFO > restLoginUsernamePasswordCallback (OK=3999 KO=0 ) 2024-05-24 23:01:47,947 INFO 2024-05-24 23:01:47,947 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,947 INFO [- ] 0% 2024-05-24 23:01:47,947 INFO waiting: 718120 / active: 7 / done: 3999 2024-05-24 23:01:47,947 INFO ================================================================================ 2024-05-24 23:01:47,947 INFO 2024-05-24 23:01:47,947 INFO 2024-05-24 23:01:47,947 INFO ================================================================================ 2024-05-24 23:01:47,947 INFO 2024-05-24 22:59:57 40s elapsed 2024-05-24 23:01:47,947 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,947 INFO > Global (OK=9979 KO=0 ) 2024-05-24 23:01:47,947 INFO > restLoginInitiate (OK=4993 KO=0 ) 2024-05-24 23:01:47,947 INFO > restLoginUsernamePasswordCallback (OK=4986 KO=0 ) 2024-05-24 23:01:47,947 INFO [...] 2024-05-24 23:01:47,950 INFO 2024-05-24 23:01:47,950 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,950 INFO [#########################################################################-] 99% 2024-05-24 23:01:47,950 INFO waiting: 3931 / active: 5 / done: 718190 2024-05-24 23:01:47,950 INFO ================================================================================ 2024-05-24 23:01:47,950 INFO 2024-05-24 23:01:47,950 INFO 2024-05-24 23:01:47,950 INFO ================================================================================ 2024-05-24 23:01:47,950 INFO 2024-05-24 23:59:32 3615s elapsed 2024-05-24 23:01:47,950 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,950 INFO > Global (OK=1438320 KO=3 ) 2024-05-24 23:01:47,950 INFO > restLoginInitiate (OK=719162 KO=2 ) 2024-05-24 23:01:47,950 INFO > restLoginUsernamePasswordCallback (OK=719158 KO=1 ) 2024-05-24 23:01:47,950 INFO ---- Errors -------------------------------------------------------------------- 2024-05-24 23:01:47,950 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-24 23:01:47,950 INFO 2024-05-24 23:01:47,950 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,951 INFO [#########################################################################-] 99% 2024-05-24 23:01:47,951 INFO waiting: 2960 / active: 5 / done: 719161 2024-05-24 23:01:47,951 INFO ================================================================================ 2024-05-24 23:01:47,951 INFO 2024-05-24 23:01:47,951 INFO 2024-05-24 23:01:47,951 INFO ================================================================================ 2024-05-24 23:01:47,951 INFO 2024-05-24 23:59:37 3620s elapsed 2024-05-24 23:01:47,951 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,951 INFO > Global (OK=1440305 KO=3 ) 2024-05-24 23:01:47,951 INFO > restLoginInitiate (OK=720155 KO=2 ) 2024-05-24 23:01:47,951 INFO > restLoginUsernamePasswordCallback (OK=720150 KO=1 ) 2024-05-24 23:01:47,951 INFO ---- Errors -------------------------------------------------------------------- 2024-05-24 23:01:47,951 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-24 23:01:47,951 INFO 2024-05-24 23:01:47,951 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,951 INFO [#########################################################################-] 99% 2024-05-24 23:01:47,951 INFO waiting: 1967 / active: 6 / done: 720153 2024-05-24 23:01:47,951 INFO ================================================================================ 2024-05-24 23:01:47,951 INFO 2024-05-24 23:01:47,951 INFO 2024-05-24 23:01:47,951 INFO ================================================================================ 2024-05-24 23:01:47,951 INFO 2024-05-24 23:59:42 3625s elapsed 2024-05-24 23:01:47,951 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,951 INFO > Global (OK=1442220 KO=3 ) 2024-05-24 23:01:47,951 INFO > restLoginInitiate (OK=721112 KO=2 ) 2024-05-24 23:01:47,951 INFO > restLoginUsernamePasswordCallback (OK=721108 KO=1 ) 2024-05-24 23:01:47,951 INFO ---- Errors -------------------------------------------------------------------- 2024-05-24 23:01:47,951 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-24 23:01:47,951 INFO 2024-05-24 23:01:47,951 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,951 INFO [#########################################################################-] 99% 2024-05-24 23:01:47,951 INFO waiting: 1011 / active: 4 / done: 721111 2024-05-24 23:01:47,951 INFO ================================================================================ 2024-05-24 23:01:47,951 INFO 2024-05-24 23:01:47,951 INFO 2024-05-24 23:01:47,951 INFO ================================================================================ 2024-05-24 23:01:47,951 INFO 2024-05-24 23:59:47 3630s elapsed 2024-05-24 23:01:47,951 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,951 INFO > Global (OK=1444239 KO=3 ) 2024-05-24 23:01:47,951 INFO > restLoginInitiate (OK=722124 KO=2 ) 2024-05-24 23:01:47,951 INFO > restLoginUsernamePasswordCallback (OK=722115 KO=1 ) 2024-05-24 23:01:47,952 INFO ---- Errors -------------------------------------------------------------------- 2024-05-24 23:01:47,952 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-24 23:01:47,952 INFO 2024-05-24 23:01:47,952 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,952 INFO [#########################################################################-] 99% 2024-05-24 23:01:47,952 INFO waiting: 0 / active: 8 / done: 722118 2024-05-24 23:01:47,952 INFO ================================================================================ 2024-05-24 23:01:47,952 INFO 2024-05-24 23:01:47,952 INFO 2024-05-24 23:01:47,952 INFO ================================================================================ 2024-05-24 23:01:47,952 INFO 2024-05-24 23:59:47 3630s elapsed 2024-05-24 23:01:47,952 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 23:01:47,952 INFO > Global (OK=1444247 KO=3 ) 2024-05-24 23:01:47,952 INFO > restLoginInitiate (OK=722124 KO=2 ) 2024-05-24 23:01:47,952 INFO > restLoginUsernamePasswordCallback (OK=722123 KO=1 ) 2024-05-24 23:01:47,952 INFO ---- Errors -------------------------------------------------------------------- 2024-05-24 23:01:47,952 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-24 23:01:47,952 INFO 2024-05-24 23:01:47,952 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 23:01:47,952 INFO [##########################################################################]100% 2024-05-24 23:01:47,952 INFO waiting: 0 / active: 0 / done: 722126 2024-05-24 23:01:47,952 INFO ================================================================================ 2024-05-24 23:01:47,952 INFO 2024-05-24 23:01:47,952 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-24 23:01:47,952 INFO 2024-05-24 23:01:47,952 INFO ---------------------- 2024-05-24 23:01:47,952 INFO [24/May/2024 23:59:47] Create tar file login-tree-200.tar.gz 2024-05-24 23:01:47,952 INFO ---------------------- 2024-05-24 23:01:47,952 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-24 23:01:47,952 INFO RESULT_FOLDER=login-tree-200 2024-05-24 23:01:47,952 INFO SIMULATION_PATH=login-tree-200/loadtest-20240524215917116 2024-05-24 23:01:47,952 INFO PWD=/results/gatling/login-tree-200/loadtest-20240524215917116 2024-05-24 23:01:47,952 INFO [cmd] tar -czvf /results/gatling//login-tree-200.tar.gz --exclude=simulation.log * 2024-05-24 23:01:47,952 INFO /results/gatling 2024-05-24 23:01:47,952 INFO 2024-05-24 23:01:47,952 INFO ---------------------- 2024-05-24 23:01:47,952 INFO [24/May/2024 23:59:47] Update /results/gatling//latest 2024-05-24 23:01:47,952 INFO ---------------------- 2024-05-24 23:01:47,952 INFO 2024-05-24 23:01:47,952 INFO ---------------------- 2024-05-24 23:01:47,953 INFO [24/May/2024 23:59:47] Update /results/gatling//latest.tar.gz 2024-05-24 23:01:47,953 INFO ---------------------- 2024-05-24 23:01:47,953 INFO ________________________________________________________________________________ [2024-05-24 23:01:47] login-tree-200 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-24 23:01:47,953 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/stderr.txt is small (0 lines), printing all lines: 2024-05-24 23:01:47,953 DEBUG ----- output ----- 2024-05-24 23:01:47,953 DEBUG 2024-05-24 23:01:47,953 INFO ________________________________________________________________________________ [2024-05-24 23:01:47] 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-05-24 23:01:47] login-tree-200 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-24 23:01:47] login-tree-200 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped